[11:23:38.674] New invocation is queued and will start shortly
[11:23:38.674] Triggered by user:v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com
[11:23:40.024] Starting the invocation (attempt 1)
[11:23:40.058] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/bd997a77ae2137076f05352b09729f3a41af5739"
[11:23:40.058] Reconstructed gitiles commit from tags
[11:23:40.058] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:23:40.058] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:23:40.058] Buildbucket request:
{
"requestId": "8983065870778836160",
"builder": {
"project": "v8",
"bucket": "ci.br.beta",
"builder": "V8 Win64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"buildbucket": {
"properties": {
"newest_gitiles_revision": "bd997a77ae2137076f05352b09729f3a41af5739",
"oldest_gitiles_revision": "bd997a77ae2137076f05352b09729f3a41af5739",
"parent_build": "https://cr-buildbucket.appspot.com/build/8817651100768816625",
"parent_buildername": "V8 Win64 - builder",
"parent_gn_args": [
"dcheck_always_on = false",
"is_component_build = false",
"is_debug = false",
"symbol_level = 1",
"target_cpu = \"x64\"",
"use_goma = true",
"v8_enable_google_benchmark = true"
],
"parent_got_revision": "bd997a77ae2137076f05352b09729f3a41af5739",
"parent_got_revision_cp": "refs/branch-heads/10.1@{#17}",
"parent_test_spec": {
"swarming_dimensions": {
"os": "Windows-10-15063"
},
"swarming_task_attrs": {
},
"tests": [
[
"mozilla",
1,
null,
"",
[
],
{
},
{
}
],
[
"test262",
1,
"default",
"default",
[
],
{
},
{
}
],
[
"v8testing",
1,
null,
"",
[
],
{
},
{
}
],
[
"v8testing",
1,
"extra",
"extra",
[
],
{
},
{
}
]
]
},
"revision": "bd997a77ae2137076f05352b09729f3a41af5739",
"swarm_hashes": {
"bot_default": "c2be20d8bc3409ae3208a2927fd27368d6f87667888d86d2768ea29db44e50e5/319",
"mozilla": "6c3b422b2fd309d691747c092983a84875146511aea883d3a7ddc8dd3bca45f5/318",
"test262": "3bb1846b01422c060bf428c2569fe204caef287d8efd4803e272a9125aeaf745/403"
}
},
"tags": [
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/bd997a77ae2137076f05352b09729f3a41af5739",
"gitiles_ref:refs/branch-heads/10.1",
"parent_buildername:V8 Win64 - builder",
"parent_buildnumber:82",
"user_agent:recipe"
]
},
"id": "65e024a8-8c2f-4b1b-8721-3cf9d0ab7184",
"title": "V8 Win64 - builder/82"
}
]
},
"newest_gitiles_revision": "bd997a77ae2137076f05352b09729f3a41af5739",
"oldest_gitiles_revision": "bd997a77ae2137076f05352b09729f3a41af5739",
"parent_build": "https://cr-buildbucket.appspot.com/build/8817651100768816625",
"parent_buildername": "V8 Win64 - builder",
"parent_gn_args": [
"dcheck_always_on = false",
"is_component_build = false",
"is_debug = false",
"symbol_level = 1",
"target_cpu = \"x64\"",
"use_goma = true",
"v8_enable_google_benchmark = true"
],
"parent_got_revision": "bd997a77ae2137076f05352b09729f3a41af5739",
"parent_got_revision_cp": "refs/branch-heads/10.1@{#17}",
"parent_test_spec": {
"swarming_dimensions": {
"os": "Windows-10-15063"
},
"swarming_task_attrs": {
},
"tests": [
[
"mozilla",
1,
null,
"",
[
],
{
},
{
}
],
[
"test262",
1,
"default",
"default",
[
],
{
},
{
}
],
[
"v8testing",
1,
null,
"",
[
],
{
},
{
}
],
[
"v8testing",
1,
"extra",
"extra",
[
],
{
},
{
}
]
]
},
"swarm_hashes": {
"bot_default": "c2be20d8bc3409ae3208a2927fd27368d6f87667888d86d2768ea29db44e50e5/319",
"mozilla": "6c3b422b2fd309d691747c092983a84875146511aea883d3a7ddc8dd3bca45f5/318",
"test262": "3bb1846b01422c060bf428c2569fe204caef287d8efd4803e272a9125aeaf745/403"
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "bd997a77ae2137076f05352b09729f3a41af5739",
"ref": "refs/branch-heads/10.1"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8983065870778836160"
},
{
"key": "scheduler_job_id",
"value": "v8/ci.br.beta-V8 Win64"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "V8 Win64 - builder"
},
{
"key": "parent_buildnumber",
"value": "82"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:23:40.679] Scheduled build:
{
"id": "8817635808596648337",
"builder": {
"project": "v8",
"bucket": "ci.br.beta",
"builder": "V8 Win64"
},
"number": 471,
"createdBy": "project:v8",
"createTime": "2022-04-06T11:23:40.125569304Z",
"updateTime": "2022-04-06T11:23:40.125569304Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "bd997a77ae2137076f05352b09729f3a41af5739",
"ref": "refs/branch-heads/10.1"
}
}
}
[11:23:40.679] Task URL: https://cr-buildbucket.appspot.com/build/8817635808596648337
[11:23:40.679] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:2:0) after 5m48s
[11:29:28.796] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:2:0)
[11:29:28.830] Build status: SCHEDULED
[11:29:28.830] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:3:0) after 3m10s
[11:32:38.976] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:3:0)
[11:32:39.013] Build status: SCHEDULED
[11:32:39.013] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:4:0) after 8m57s
[11:41:36.148] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:4:0)
[11:41:36.200] Build status: SCHEDULED
[11:41:36.200] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:5:0) after 7m58s
[11:49:34.370] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:5:0)
[11:49:34.400] Build status: SCHEDULED
[11:49:34.400] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:6:0) after 7m48s
[11:57:22.809] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:6:0)
[11:57:22.850] Build status: SCHEDULED
[11:57:22.850] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:7:0) after 3m58s
[12:01:20.909] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:7:0)
[12:01:20.941] Build status: SCHEDULED
[12:01:20.941] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:8:0) after 6m27s
[12:07:48.173] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:8:0)
[12:07:48.193] Build status: SCHEDULED
[12:07:48.193] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:9:0) after 9m49s
[12:17:37.211] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:9:0)
[12:17:37.247] Build status: SCHEDULED
[12:17:37.247] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:10:0) after 7m48s
[12:25:25.705] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:10:0)
[12:25:25.744] Build status: SCHEDULED
[12:25:25.744] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:11:0) after 3m10s
[12:28:36.086] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:11:0)
[12:28:36.112] Build status: SCHEDULED
[12:28:36.112] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:12:0) after 4m18s
[12:32:54.263] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:12:0)
[12:32:54.293] Build status: SCHEDULED
[12:32:54.293] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:13:0) after 9m1s
[12:41:55.418] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:13:0)
[12:41:55.474] Build status: SCHEDULED
[12:41:55.474] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:14:0) after 8m16s
[12:50:11.590] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:14:0)
[12:50:11.622] Build status: SCHEDULED
[12:50:11.622] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:15:0) after 5m49s
[12:56:00.693] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:15:0)
[12:56:00.721] Build status: SCHEDULED
[12:56:00.721] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:16:0) after 7m59s
[13:03:59.743] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:16:0)
[13:03:59.783] Build status: SCHEDULED
[13:03:59.783] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:17:0) after 1m37s
[13:05:36.813] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:17:0)
[13:05:36.848] Build status: SCHEDULED
[13:05:36.848] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:18:0) after 1m37s
[13:07:13.876] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:18:0)
[13:07:13.908] Build status: SCHEDULED
[13:07:13.908] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:19:0) after 8m44s
[13:15:58.089] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:19:0)
[13:15:58.241] Build status: SCHEDULED
[13:15:58.241] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:20:0) after 8m40s
[13:24:38.382] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:20:0)
[13:24:38.412] Build status: SCHEDULED
[13:24:38.412] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:21:0) after 5m43s
[13:30:21.638] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:21:0)
[13:30:21.681] Build status: SCHEDULED
[13:30:21.681] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:22:0) after 6m27s
[13:36:48.805] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:22:0)
[13:36:48.836] Build status: SCHEDULED
[13:36:48.836] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:23:0) after 9m23s
[13:46:11.948] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:23:0)
[13:46:11.983] Build status: SCHEDULED
[13:46:11.983] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:24:0) after 7m30s
[13:53:42.263] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:24:0)
[13:53:42.301] Build status: SCHEDULED
[13:53:42.301] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:25:0) after 7m11s
[14:00:53.344] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:25:0)
[14:00:53.400] Build status: SCHEDULED
[14:00:53.400] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:26:0) after 3m59s
[14:04:52.768] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:26:0)
[14:04:52.798] Build status: SCHEDULED
[14:04:52.798] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:27:0) after 6m5s
[14:10:58.524] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:27:0)
[14:10:58.566] Build status: SCHEDULED
[14:10:58.566] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:28:0) after 6m13s
[14:17:11.947] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:28:0)
[14:17:11.978] Build status: SCHEDULED
[14:17:11.978] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:29:0) after 1m30s
[14:18:41.999] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:29:0)
[14:18:42.076] Build status: SCHEDULED
[14:18:42.076] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:30:0) after 4m30s
[14:23:12.157] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:30:0)
[14:23:12.264] Build status: SCHEDULED
[14:23:12.264] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:31:0) after 5m21s
[14:28:33.284] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:31:0)
[14:28:33.304] Build status: SCHEDULED
[14:28:33.304] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:32:0) after 6m20s
[14:34:53.703] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:32:0)
[14:34:53.760] Build status: SCHEDULED
[14:34:53.760] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:33:0) after 1m28s
[14:36:22.045] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:33:0)
[14:36:22.076] Build status: SCHEDULED
[14:36:22.076] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:34:0) after 4m4s
[14:40:26.100] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:34:0)
[14:40:26.130] Build status: SCHEDULED
[14:40:26.131] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:35:0) after 3m6s
[14:43:32.295] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:35:0)
[14:43:32.330] Build status: SCHEDULED
[14:43:32.330] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:36:0) after 1m36s
[14:45:08.439] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:36:0)
[14:45:08.477] Build status: SCHEDULED
[14:45:08.477] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:37:0) after 6m30s
[14:51:38.876] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:37:0)
[14:51:38.905] Build status: SCHEDULED
[14:51:38.905] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:38:0) after 4m33s
[14:56:12.055] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:38:0)
[14:56:12.097] Build status: SCHEDULED
[14:56:12.097] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:39:0) after 1m37s
[14:57:49.119] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:39:0)
[14:57:49.153] Build status: SCHEDULED
[14:57:49.153] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:40:0) after 9m4s
[15:06:53.389] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:40:0)
[15:06:53.423] Build status: SCHEDULED
[15:06:53.423] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:41:0) after 2m56s
[15:09:49.439] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:41:0)
[15:09:49.542] Build status: SCHEDULED
[15:09:49.542] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:42:0) after 1m41s
[15:11:30.736] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:42:0)
[15:11:30.782] Build status: SCHEDULED
[15:11:30.782] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:43:0) after 9m17s
[15:14:54.927] Received PubSub notification, asking Buildbucket for the build status
[15:14:54.984] Build status: STARTED
[15:20:47.801] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:43:0)
[15:20:47.834] Build status: STARTED
[15:20:47.834] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:45:0) after 3m49s
[15:24:36.890] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:45:0)
[15:24:36.920] Build status: STARTED
[15:24:36.920] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:46:0) after 9m36s
[15:34:13.042] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:46:0)
[15:34:13.076] Build status: STARTED
[15:34:13.076] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Win64:8983065870778836160:47:0) after 5m39s
[15:34:21.306] Received PubSub notification, asking Buildbucket for the build status
[15:34:21.347] Build:
{
"id": "8817635808596648337",
"builder": {
"project": "v8",
"bucket": "ci.br.beta",
"builder": "V8 Win64"
},
"number": 471,
"createdBy": "project:v8",
"createTime": "2022-04-06T11:23:40.125569304Z",
"startTime": "2022-04-06T15:14:52.479043Z",
"endTime": "2022-04-06T15:34:21.022200470Z",
"updateTime": "2022-04-06T15:34:21.022200470Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "bd997a77ae2137076f05352b09729f3a41af5739",
"ref": "refs/branch-heads/10.1"
}
}
}
[15:34:21.347] Invocation finished in 4h10m42.686788902s with status SUCCEEDED