[15:36:15.252] New invocation is queued and will start shortly
[15:36:15.252] Triggered by user:v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com
[15:36:16.447] Starting the invocation (attempt 1)
[15:36:16.473] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/a63f677440bc0f6273414aa6a1a39f7c2f374aea"
[15:36:16.473] Reconstructed gitiles commit from tags
[15:36:16.473] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:36:16.473] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:36:16.473] Buildbucket request:
{
"requestId": "8945724027790509408",
"builder": {
"project": "v8",
"bucket": "ci.br.stable",
"builder": "V8 Linux64 - internal snapshot"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8945724027790509408",
"job": "v8/ci.br.stable-V8 Linux64 - internal snapshot",
"triggers": [
{
"buildbucket": {
"properties": {
"newest_gitiles_revision": "a63f677440bc0f6273414aa6a1a39f7c2f374aea",
"oldest_gitiles_revision": "a63f677440bc0f6273414aa6a1a39f7c2f374aea",
"parent_build": "https://cr-buildbucket.appspot.com/build/8780297926424961377",
"parent_buildername": "V8 Linux64 - internal snapshot - builder",
"parent_gn_args": [
"dcheck_always_on = false",
"is_component_build = false",
"is_debug = false",
"target_cpu = \"x64\"",
"use_goma = false",
"use_remoteexec = true",
"v8_enable_google_benchmark = true",
"v8_use_external_startup_data = false"
],
"parent_got_revision": "a63f677440bc0f6273414aa6a1a39f7c2f374aea",
"parent_got_revision_cp": "refs/branch-heads/11.4@{#26}",
"parent_test_spec": {
"swarming_dimensions": {
"os": "Ubuntu-18.04"
},
"swarming_task_attrs": {
},
"tests": [
[
"v8testing",
1,
null,
"",
[
],
{
},
{
}
]
]
},
"revision": "a63f677440bc0f6273414aa6a1a39f7c2f374aea",
"swarm_hashes": {
"bot_default": "c14514831cc044dd20a65a0ecfa5721968aa10765b1b6f10b2ea1dbe28653e65/320"
}
},
"tags": [
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/a63f677440bc0f6273414aa6a1a39f7c2f374aea",
"gitiles_ref:refs/branch-heads/11.4",
"parent_buildername:V8 Linux64 - internal snapshot - builder",
"parent_buildnumber:213",
"user_agent:recipe"
]
},
"id": "6df0ad45-24a0-474d-a6b3-5205878b5840",
"title": "V8 Linux64 - internal snapshot - builder/213"
}
]
},
"newest_gitiles_revision": "a63f677440bc0f6273414aa6a1a39f7c2f374aea",
"oldest_gitiles_revision": "a63f677440bc0f6273414aa6a1a39f7c2f374aea",
"parent_build": "https://cr-buildbucket.appspot.com/build/8780297926424961377",
"parent_buildername": "V8 Linux64 - internal snapshot - builder",
"parent_gn_args": [
"dcheck_always_on = false",
"is_component_build = false",
"is_debug = false",
"target_cpu = \"x64\"",
"use_goma = false",
"use_remoteexec = true",
"v8_enable_google_benchmark = true",
"v8_use_external_startup_data = false"
],
"parent_got_revision": "a63f677440bc0f6273414aa6a1a39f7c2f374aea",
"parent_got_revision_cp": "refs/branch-heads/11.4@{#26}",
"parent_test_spec": {
"swarming_dimensions": {
"os": "Ubuntu-18.04"
},
"swarming_task_attrs": {
},
"tests": [
[
"v8testing",
1,
null,
"",
[
],
{
},
{
}
]
]
},
"swarm_hashes": {
"bot_default": "c14514831cc044dd20a65a0ecfa5721968aa10765b1b6f10b2ea1dbe28653e65/320"
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "a63f677440bc0f6273414aa6a1a39f7c2f374aea",
"ref": "refs/branch-heads/11.4"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8945724027790509408"
},
{
"key": "scheduler_job_id",
"value": "v8/ci.br.stable-V8 Linux64 - internal snapshot"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "V8 Linux64 - internal snapshot - builder"
},
{
"key": "parent_buildnumber",
"value": "213"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[15:36:16.952] Scheduled build:
{
"id": "8780293965793296017",
"builder": {
"project": "v8",
"bucket": "ci.br.stable",
"builder": "V8 Linux64 - internal snapshot"
},
"number": 422,
"createdBy": "project:v8",
"createTime": "2023-05-23T15:36:16.534984511Z",
"updateTime": "2023-05-23T15:36:16.534984511Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "a63f677440bc0f6273414aa6a1a39f7c2f374aea",
"ref": "refs/branch-heads/11.4"
}
}
}
[15:36:16.952] Task URL: https://cr-buildbucket.appspot.com/build/8780293965793296017
[15:36:16.952] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:2:0) after 2m28s
[15:38:45.016] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:2:0)
[15:38:45.033] Build status: SCHEDULED
[15:38:45.033] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:3:0) after 9m47s
[15:48:32.088] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:3:0)
[15:48:32.129] Build status: SCHEDULED
[15:48:32.129] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:4:0) after 8m24s
[15:56:56.146] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:4:0)
[15:56:56.166] Build status: SCHEDULED
[15:56:56.166] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:5:0) after 6m59s
[16:03:55.184] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:5:0)
[16:03:55.205] Build status: SCHEDULED
[16:03:55.205] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:6:0) after 7m26s
[16:11:21.248] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:6:0)
[16:11:21.277] Build status: SCHEDULED
[16:11:21.277] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:7:0) after 2m51s
[16:14:12.321] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:7:0)
[16:14:12.352] Build status: SCHEDULED
[16:14:12.352] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:8:0) after 3m12s
[16:17:24.370] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:8:0)
[16:17:24.386] Build status: SCHEDULED
[16:17:24.386] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:9:0) after 8m11s
[16:25:35.412] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:9:0)
[16:25:35.434] Build status: SCHEDULED
[16:25:35.434] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:10:0) after 4m42s
[16:30:17.430] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:10:0)
[16:30:17.505] Build status: SCHEDULED
[16:30:17.505] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:11:0) after 2m39s
[16:32:56.551] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:11:0)
[16:32:56.571] Build status: SCHEDULED
[16:32:56.571] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:12:0) after 9m27s
[16:42:23.594] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:12:0)
[16:42:23.620] Build status: SCHEDULED
[16:42:23.620] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:13:0) after 6m40s
[16:49:03.692] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:13:0)
[16:49:03.782] Build status: SCHEDULED
[16:49:03.782] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:14:0) after 2m49s
[16:51:52.806] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:14:0)
[16:51:52.824] Build status: SCHEDULED
[16:51:52.824] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:15:0) after 8m10s
[17:00:02.895] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:15:0)
[17:00:02.937] Build status: SCHEDULED
[17:00:02.937] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:16:0) after 3m59s
[17:04:01.972] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:16:0)
[17:04:01.992] Build status: SCHEDULED
[17:04:01.992] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:17:0) after 1m4s
[17:05:05.983] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:17:0)
[17:05:06.015] Build status: SCHEDULED
[17:05:06.015] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:18:0) after 5m42s
[17:10:48.041] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:18:0)
[17:10:48.061] Build status: SCHEDULED
[17:10:48.061] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:19:0) after 2m23s
[17:13:11.223] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:19:0)
[17:13:11.245] Build status: SCHEDULED
[17:13:11.245] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:20:0) after 5m1s
[17:18:12.278] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:20:0)
[17:18:12.297] Build status: SCHEDULED
[17:18:12.297] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:21:0) after 5m51s
[17:24:03.316] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:21:0)
[17:24:03.395] Build status: SCHEDULED
[17:24:03.395] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:22:0) after 7m51s
[17:31:54.493] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:22:0)
[17:31:54.518] Build status: SCHEDULED
[17:31:54.518] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:23:0) after 3m42s
[17:35:36.593] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:23:0)
[17:35:36.621] Build status: SCHEDULED
[17:35:36.621] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:24:0) after 3m13s
[17:38:49.644] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:24:0)
[17:38:49.687] Build status: SCHEDULED
[17:38:49.687] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:25:0) after 9m57s
[17:48:46.707] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:25:0)
[17:48:46.725] Build status: SCHEDULED
[17:48:46.725] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:26:0) after 5m10s
[17:53:56.715] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:26:0)
[17:53:56.746] Build status: SCHEDULED
[17:53:56.746] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:27:0) after 4m56s
[17:58:52.824] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:27:0)
[17:58:52.843] Build status: SCHEDULED
[17:58:52.843] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:28:0) after 9m3s
[18:07:55.880] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:28:0)
[18:07:55.912] Build status: SCHEDULED
[18:07:55.912] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:29:0) after 2m24s
[18:10:19.946] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:29:0)
[18:10:19.996] Build status: SCHEDULED
[18:10:19.996] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:30:0) after 5m15s
[18:15:35.014] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:30:0)
[18:15:35.044] Build status: SCHEDULED
[18:15:35.044] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:31:0) after 2m22s
[18:17:57.069] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:31:0)
[18:17:57.130] Build status: SCHEDULED
[18:17:57.130] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:32:0) after 8m7s
[18:25:29.381] Received PubSub notification, asking Buildbucket for the build status
[18:25:29.403] Build status: STARTED
[18:26:04.142] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:32:0)
[18:26:04.194] Build status: STARTED
[18:26:04.194] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:34:0) after 5m50s
[18:31:54.217] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:34:0)
[18:31:54.240] Build status: STARTED
[18:31:54.240] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:35:0) after 1m21s
[18:33:15.260] Handling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:35:0)
[18:33:15.282] Build status: STARTED
[18:33:15.282] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.stable-V8 Linux64 - internal snapshot:8945724027790509408:36:0) after 5m39s
[18:37:42.617] Received PubSub notification, asking Buildbucket for the build status
[18:37:42.644] Build:
{
"id": "8780293965793296017",
"builder": {
"project": "v8",
"bucket": "ci.br.stable",
"builder": "V8 Linux64 - internal snapshot"
},
"number": 422,
"createdBy": "project:v8",
"createTime": "2023-05-23T15:36:16.534984511Z",
"startTime": "2023-05-23T18:25:28.246569Z",
"endTime": "2023-05-23T18:37:42.182128298Z",
"updateTime": "2023-05-23T18:37:42.182128298Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "a63f677440bc0f6273414aa6a1a39f7c2f374aea",
"ref": "refs/branch-heads/11.4"
}
}
}
[18:37:42.644] Invocation finished in 3h1m27.399303683s with status SUCCEEDED