[14:56:06.332] New invocation is queued and will start shortly
[14:56:06.332] Triggered by user:v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com
[14:56:07.614] Starting the invocation (attempt 1)
[14:56:07.655] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/9d410faaeb2d6520b484965eaea33cabc4447e61"
[14:56:07.655] Reconstructed gitiles commit from tags
[14:56:07.655] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:56:07.655] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:56:07.656] Buildbucket request:
{
"requestId": "8994467721658522992",
"builder": {
"project": "v8",
"bucket": "ci.br.beta",
"builder": "V8 Linux - gc stress"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"buildbucket": {
"properties": {
"newest_gitiles_revision": "9d410faaeb2d6520b484965eaea33cabc4447e61",
"oldest_gitiles_revision": "9d410faaeb2d6520b484965eaea33cabc4447e61",
"parent_build": "https://cr-buildbucket.appspot.com/build/8829038869583099713",
"parent_buildername": "V8 Linux - debug builder",
"parent_gn_args": [
"is_component_build = true",
"is_debug = true",
"target_cpu = \"x86\"",
"use_goma = true",
"v8_enable_backtrace = true",
"v8_enable_google_benchmark = true",
"v8_enable_slow_dchecks = true"
],
"parent_got_revision": "9d410faaeb2d6520b484965eaea33cabc4447e61",
"parent_got_revision_cp": "refs/branch-heads/9.7@{#29}",
"parent_test_spec": {
"swarming_dimensions": {
"os": "Ubuntu-18.04"
},
"swarming_task_attrs": {
},
"tests": [
[
"d8testing",
5,
null,
"",
[
"--gc-stress"
],
{
},
{
}
],
[
"mjsunit",
2,
"slow_path",
"slow_path",
[
"--gc-stress"
],
{
},
{
}
]
]
},
"revision": "9d410faaeb2d6520b484965eaea33cabc4447e61",
"swarm_hashes": {
"benchmarks": "16bc57d8b1891f877acc303031afe1f1d9eb49fbd13805b116d12a8927e2cae0/318",
"bot_default": "4ea6f385163773e05aaed34a7acf1ac4106cf6ae02576ac18a764ad4dd6e42f6/319",
"d8_default": "ff9b0d00b1bb4e86810513b45ea077dc5e4b5d1ec8db88201e19e5b38d58f37c/319",
"mjsunit": "59480b34794568554ebfc26dfe7645faaf1616e599c54067b2999307668c8e7b/318",
"mozilla": "605de5be605ffa2f0af3465f65c9c78cb11009e1078f1fea21e3c4ae3bff5737/318",
"optimize_for_size": "38ca7e8e8bf1d9857b2791eccb35908111437d22812c242380ff97b2c4ce8261/319",
"test262": "4ad0cf3c43ddaee6af4706b2309670c6e8e88e156b1d7edf6aad2c57b44c4f2e/318"
}
},
"tags": [
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/9d410faaeb2d6520b484965eaea33cabc4447e61",
"gitiles_ref:refs/branch-heads/9.7",
"parent_buildername:V8 Linux - debug builder",
"parent_buildnumber:422",
"user_agent:recipe"
]
},
"id": "567d7b35-bd94-4c97-bade-2851e872425e",
"title": "V8 Linux - debug builder/422"
}
]
},
"newest_gitiles_revision": "9d410faaeb2d6520b484965eaea33cabc4447e61",
"oldest_gitiles_revision": "9d410faaeb2d6520b484965eaea33cabc4447e61",
"parent_build": "https://cr-buildbucket.appspot.com/build/8829038869583099713",
"parent_buildername": "V8 Linux - debug builder",
"parent_gn_args": [
"is_component_build = true",
"is_debug = true",
"target_cpu = \"x86\"",
"use_goma = true",
"v8_enable_backtrace = true",
"v8_enable_google_benchmark = true",
"v8_enable_slow_dchecks = true"
],
"parent_got_revision": "9d410faaeb2d6520b484965eaea33cabc4447e61",
"parent_got_revision_cp": "refs/branch-heads/9.7@{#29}",
"parent_test_spec": {
"swarming_dimensions": {
"os": "Ubuntu-18.04"
},
"swarming_task_attrs": {
},
"tests": [
[
"d8testing",
5,
null,
"",
[
"--gc-stress"
],
{
},
{
}
],
[
"mjsunit",
2,
"slow_path",
"slow_path",
[
"--gc-stress"
],
{
},
{
}
]
]
},
"swarm_hashes": {
"benchmarks": "16bc57d8b1891f877acc303031afe1f1d9eb49fbd13805b116d12a8927e2cae0/318",
"bot_default": "4ea6f385163773e05aaed34a7acf1ac4106cf6ae02576ac18a764ad4dd6e42f6/319",
"d8_default": "ff9b0d00b1bb4e86810513b45ea077dc5e4b5d1ec8db88201e19e5b38d58f37c/319",
"mjsunit": "59480b34794568554ebfc26dfe7645faaf1616e599c54067b2999307668c8e7b/318",
"mozilla": "605de5be605ffa2f0af3465f65c9c78cb11009e1078f1fea21e3c4ae3bff5737/318",
"optimize_for_size": "38ca7e8e8bf1d9857b2791eccb35908111437d22812c242380ff97b2c4ce8261/319",
"test262": "4ad0cf3c43ddaee6af4706b2309670c6e8e88e156b1d7edf6aad2c57b44c4f2e/318"
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "9d410faaeb2d6520b484965eaea33cabc4447e61",
"ref": "refs/branch-heads/9.7"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8994467721658522992"
},
{
"key": "scheduler_job_id",
"value": "v8/ci.br.beta-V8 Linux - gc stress"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "V8 Linux - debug builder"
},
{
"key": "parent_buildnumber",
"value": "422"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:56:08.226] Scheduled build:
{
"id": "8829037659513607889",
"builder": {
"project": "v8",
"bucket": "ci.br.beta",
"builder": "V8 Linux - gc stress"
},
"number": 397,
"createdBy": "project:v8",
"createTime": "2021-12-01T14:56:07.746196004Z",
"updateTime": "2021-12-01T14:56:07.746196004Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "9d410faaeb2d6520b484965eaea33cabc4447e61",
"ref": "refs/branch-heads/9.7"
}
}
}
[14:56:08.226] Task URL: https://cr-buildbucket.appspot.com/build/8829037659513607889
[14:56:08.226] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:2:0) after 5m10s
[15:01:18.602] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:2:0)
[15:01:18.650] Build status: SCHEDULED
[15:01:18.650] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:3:0) after 2m50s
[15:04:08.841] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:3:0)
[15:04:08.868] Build status: SCHEDULED
[15:04:08.868] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:4:0) after 1m53s
[15:06:02.095] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:4:0)
[15:06:02.137] Build status: SCHEDULED
[15:06:02.137] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:5:0) after 5m11s
[15:11:13.619] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:5:0)
[15:11:13.656] Build status: SCHEDULED
[15:11:13.656] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:6:0) after 2m21s
[15:13:34.708] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:6:0)
[15:13:34.863] Build status: SCHEDULED
[15:13:34.863] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:7:0) after 9m42s
[15:23:16.976] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:7:0)
[15:23:17.010] Build status: SCHEDULED
[15:23:17.010] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:8:0) after 4m50s
[15:28:07.104] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:8:0)
[15:28:07.141] Build status: SCHEDULED
[15:28:07.141] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:9:0) after 3m52s
[15:31:59.182] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:9:0)
[15:31:59.219] Build status: SCHEDULED
[15:31:59.219] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:10:0) after 6m15s
[15:38:14.238] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:10:0)
[15:38:14.275] Build status: SCHEDULED
[15:38:14.275] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:11:0) after 5m58s
[15:44:12.331] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:11:0)
[15:44:12.407] Build status: SCHEDULED
[15:44:12.407] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:12:0) after 9m53s
[15:54:05.567] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:12:0)
[15:54:05.601] Build status: SCHEDULED
[15:54:05.601] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:13:0) after 9m26s
[16:03:31.671] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:13:0)
[16:03:31.706] Build status: SCHEDULED
[16:03:31.706] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:14:0) after 4m43s
[16:08:14.799] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:14:0)
[16:08:14.837] Build status: SCHEDULED
[16:08:14.837] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:15:0) after 4m2s
[16:12:16.867] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:15:0)
[16:12:16.900] Build status: SCHEDULED
[16:12:16.900] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:16:0) after 2m29s
[16:14:46.095] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:16:0)
[16:14:46.131] Build status: SCHEDULED
[16:14:46.131] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:17:0) after 8m41s
[16:23:27.474] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:17:0)
[16:23:27.527] Build status: SCHEDULED
[16:23:27.527] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:18:0) after 9m0s
[16:32:27.502] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:18:0)
[16:32:27.652] Build status: SCHEDULED
[16:32:27.652] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:19:0) after 9m58s
[16:42:25.943] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:19:0)
[16:42:25.973] Build status: SCHEDULED
[16:42:25.973] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:20:0) after 6m28s
[16:48:54.090] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:20:0)
[16:48:54.139] Build status: SCHEDULED
[16:48:54.139] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:21:0) after 9m0s
[16:57:54.245] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:21:0)
[16:57:54.274] Build status: SCHEDULED
[16:57:54.274] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:22:0) after 8m16s
[17:06:10.326] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:22:0)
[17:06:10.375] Build status: SCHEDULED
[17:06:10.375] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:23:0) after 5m41s
[17:11:51.489] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:23:0)
[17:11:51.532] Build status: SCHEDULED
[17:11:51.532] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:24:0) after 1m13s
[17:13:04.655] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:24:0)
[17:13:04.691] Build status: SCHEDULED
[17:13:04.691] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:25:0) after 6m9s
[17:19:13.976] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:25:0)
[17:19:14.031] Build status: SCHEDULED
[17:19:14.031] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:26:0) after 1m50s
[17:21:04.050] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:26:0)
[17:21:04.078] Build status: SCHEDULED
[17:21:04.078] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:27:0) after 4m45s
[17:25:49.100] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:27:0)
[17:25:49.128] Build status: SCHEDULED
[17:25:49.128] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:28:0) after 8m12s
[17:34:01.185] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:28:0)
[17:34:01.229] Build status: SCHEDULED
[17:34:01.229] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:29:0) after 6m48s
[17:40:49.407] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:29:0)
[17:40:49.460] Build status: SCHEDULED
[17:40:49.460] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:30:0) after 6m47s
[17:47:36.704] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:30:0)
[17:47:36.791] Build status: SCHEDULED
[17:47:36.791] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:31:0) after 5m39s
[17:53:15.897] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:31:0)
[17:53:15.927] Build status: SCHEDULED
[17:53:15.927] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:32:0) after 2m26s
[17:55:42.111] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:32:0)
[17:55:42.141] Build status: SCHEDULED
[17:55:42.141] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:33:0) after 4m19s
[18:00:01.353] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:33:0)
[18:00:01.392] Build status: SCHEDULED
[18:00:01.392] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:34:0) after 4m1s
[18:04:02.424] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:34:0)
[18:04:02.461] Build status: SCHEDULED
[18:04:02.461] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:35:0) after 6m0s
[18:10:02.527] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:35:0)
[18:10:02.573] Build status: SCHEDULED
[18:10:02.573] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:36:0) after 9m28s
[18:19:30.651] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:36:0)
[18:19:30.685] Build status: SCHEDULED
[18:19:30.685] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:37:0) after 8m46s
[18:28:05.955] Received PubSub notification, asking Buildbucket for the build status
[18:28:05.993] Build status: STARTED
[18:28:16.781] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:37:0)
[18:28:16.820] Build status: STARTED
[18:28:16.820] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:39:0) after 8m50s
[18:37:06.966] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:39:0)
[18:37:07.064] Build status: STARTED
[18:37:07.064] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:40:0) after 8m21s
[18:45:28.284] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:40:0)
[18:45:28.316] Build status: STARTED
[18:45:28.316] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:41:0) after 5m23s
[18:50:51.382] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:41:0)
[18:50:51.417] Build status: STARTED
[18:50:51.417] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - gc stress:8994467721658522992:42:0) after 6m4s
[18:52:06.598] Received PubSub notification, asking Buildbucket for the build status
[18:52:06.749] Build:
{
"id": "8829037659513607889",
"builder": {
"project": "v8",
"bucket": "ci.br.beta",
"builder": "V8 Linux - gc stress"
},
"number": 397,
"createdBy": "project:v8",
"createTime": "2021-12-01T14:56:07.746196004Z",
"startTime": "2021-12-01T18:28:02.406737Z",
"endTime": "2021-12-01T18:52:05.908703757Z",
"updateTime": "2021-12-01T18:52:05.908703757Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "9d410faaeb2d6520b484965eaea33cabc4447e61",
"ref": "refs/branch-heads/9.7"
}
}
}
[18:52:06.749] Invocation finished in 3h56m0.431502687s with status SUCCEEDED