[16:45:25.715] New invocation is queued and will start shortly
[16:45:25.715] Triggered by user:v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com
[16:45:26.872] Starting the invocation (attempt 1)
[16:45:26.925] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/36b66b5cc99147edc594a69f4b94f7828fc94750"
[16:45:26.925] Reconstructed gitiles commit from tags
[16:45:26.925] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:45:26.927] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:45:26.927] Buildbucket request:
{
"requestId": "8983498610726564912",
"builder": {
"project": "v8",
"bucket": "ci.br.extended",
"builder": "V8 Win32"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"buildbucket": {
"properties": {
"newest_gitiles_revision": "36b66b5cc99147edc594a69f4b94f7828fc94750",
"oldest_gitiles_revision": "36b66b5cc99147edc594a69f4b94f7828fc94750",
"parent_build": "https://cr-buildbucket.appspot.com/build/8818069200197294129",
"parent_buildername": "V8 Win32 - builder",
"parent_gn_args": [
"dcheck_always_on = false",
"is_component_build = false",
"is_debug = false",
"symbol_level = 1",
"target_cpu = \"x86\"",
"use_goma = true",
"v8_enable_google_benchmark = true"
],
"parent_got_revision": "36b66b5cc99147edc594a69f4b94f7828fc94750",
"parent_got_revision_cp": "refs/branch-heads/10.0@{#16}",
"parent_test_spec": {
"swarming_dimensions": {
"cpu": "x86-64",
"os": "Windows-7-SP1"
},
"swarming_task_attrs": {
},
"tests": [
[
"mozilla",
1,
null,
"",
[
],
{
},
{
}
],
[
"test262",
1,
"default",
"default",
[
],
{
},
{
}
],
[
"v8testing",
1,
null,
"",
[
],
{
},
{
}
]
]
},
"revision": "36b66b5cc99147edc594a69f4b94f7828fc94750",
"swarm_hashes": {
"bot_default": "1516064509568718f7f038d14dbf034585f073f3e326e6c8bb8f00da159323ca/319",
"mozilla": "b201ab92f7839982c21bcefc044629453fb0adb417ae0e753d2708acc424051b/318",
"test262": "13148008204985f7f63a8e67eeb1ec95b126c2cace7d86eea047cc4159f8092c/318"
}
},
"tags": [
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/36b66b5cc99147edc594a69f4b94f7828fc94750",
"gitiles_ref:refs/branch-heads/10.0",
"parent_buildername:V8 Win32 - builder",
"parent_buildnumber:66",
"user_agent:recipe"
]
},
"id": "22562ec7-ceba-4a83-a166-d2705c055e16",
"title": "V8 Win32 - builder/66"
}
]
},
"newest_gitiles_revision": "36b66b5cc99147edc594a69f4b94f7828fc94750",
"oldest_gitiles_revision": "36b66b5cc99147edc594a69f4b94f7828fc94750",
"parent_build": "https://cr-buildbucket.appspot.com/build/8818069200197294129",
"parent_buildername": "V8 Win32 - builder",
"parent_gn_args": [
"dcheck_always_on = false",
"is_component_build = false",
"is_debug = false",
"symbol_level = 1",
"target_cpu = \"x86\"",
"use_goma = true",
"v8_enable_google_benchmark = true"
],
"parent_got_revision": "36b66b5cc99147edc594a69f4b94f7828fc94750",
"parent_got_revision_cp": "refs/branch-heads/10.0@{#16}",
"parent_test_spec": {
"swarming_dimensions": {
"cpu": "x86-64",
"os": "Windows-7-SP1"
},
"swarming_task_attrs": {
},
"tests": [
[
"mozilla",
1,
null,
"",
[
],
{
},
{
}
],
[
"test262",
1,
"default",
"default",
[
],
{
},
{
}
],
[
"v8testing",
1,
null,
"",
[
],
{
},
{
}
]
]
},
"swarm_hashes": {
"bot_default": "1516064509568718f7f038d14dbf034585f073f3e326e6c8bb8f00da159323ca/319",
"mozilla": "b201ab92f7839982c21bcefc044629453fb0adb417ae0e753d2708acc424051b/318",
"test262": "13148008204985f7f63a8e67eeb1ec95b126c2cace7d86eea047cc4159f8092c/318"
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "36b66b5cc99147edc594a69f4b94f7828fc94750",
"ref": "refs/branch-heads/10.0"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8983498610726564912"
},
{
"key": "scheduler_job_id",
"value": "v8/ci.br.extended-V8 Win32"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "V8 Win32 - builder"
},
{
"key": "parent_buildnumber",
"value": "66"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:45:27.591] Scheduled build:
{
"id": "8818068548684472657",
"builder": {
"project": "v8",
"bucket": "ci.br.extended",
"builder": "V8 Win32"
},
"number": 62,
"createdBy": "project:v8",
"createTime": "2022-04-01T16:45:27.017994161Z",
"updateTime": "2022-04-01T16:45:27.017994161Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "36b66b5cc99147edc594a69f4b94f7828fc94750",
"ref": "refs/branch-heads/10.0"
}
}
}
[16:45:27.591] Task URL: https://cr-buildbucket.appspot.com/build/8818068548684472657
[16:45:27.591] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:2:0) after 7m26s
[16:52:53.647] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:2:0)
[16:52:53.750] Build status: SCHEDULED
[16:52:53.750] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:3:0) after 1m4s
[16:53:57.769] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:3:0)
[16:53:57.808] Build status: SCHEDULED
[16:53:57.808] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:4:0) after 1m46s
[16:55:43.826] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:4:0)
[16:55:43.912] Build status: SCHEDULED
[16:55:43.912] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:5:0) after 4m27s
[17:00:10.930] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:5:0)
[17:00:10.966] Build status: SCHEDULED
[17:00:10.966] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:6:0) after 8m29s
[17:08:40.034] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:6:0)
[17:08:40.061] Build status: SCHEDULED
[17:08:40.061] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:7:0) after 2m1s
[17:10:41.187] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:7:0)
[17:10:41.235] Build status: SCHEDULED
[17:10:41.235] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:8:0) after 3m28s
[17:14:09.257] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:8:0)
[17:14:09.288] Build status: SCHEDULED
[17:14:09.288] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:9:0) after 9m44s
[17:23:53.756] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:9:0)
[17:23:53.778] Build status: SCHEDULED
[17:23:53.778] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:10:0) after 4m47s
[17:28:40.955] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:10:0)
[17:28:40.985] Build status: SCHEDULED
[17:28:40.985] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:11:0) after 8m26s
[17:37:07.023] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:11:0)
[17:37:07.061] Build status: SCHEDULED
[17:37:07.061] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:12:0) after 6m8s
[17:43:15.099] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:12:0)
[17:43:15.129] Build status: SCHEDULED
[17:43:15.129] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:13:0) after 1m47s
[17:45:02.173] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:13:0)
[17:45:02.194] Build status: SCHEDULED
[17:45:02.194] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:14:0) after 8m35s
[17:53:37.215] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:14:0)
[17:53:37.245] Build status: SCHEDULED
[17:53:37.245] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:15:0) after 2m50s
[17:56:27.265] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:15:0)
[17:56:27.285] Build status: SCHEDULED
[17:56:27.285] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:16:0) after 3m30s
[17:59:57.307] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:16:0)
[17:59:57.325] Build status: SCHEDULED
[17:59:57.325] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:17:0) after 2m43s
[18:02:40.356] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:17:0)
[18:02:40.427] Build status: SCHEDULED
[18:02:40.427] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:18:0) after 2m38s
[18:05:18.985] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:18:0)
[18:05:19.167] Build status: SCHEDULED
[18:05:19.167] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:19:0) after 7m51s
[18:13:10.186] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:19:0)
[18:13:10.217] Build status: SCHEDULED
[18:13:10.217] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:20:0) after 7m40s
[18:20:50.276] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:20:0)
[18:20:50.311] Build status: SCHEDULED
[18:20:50.311] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:21:0) after 5m8s
[18:25:58.685] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:21:0)
[18:25:58.727] Build status: SCHEDULED
[18:25:58.727] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:22:0) after 2m22s
[18:28:20.857] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:22:0)
[18:28:20.894] Build status: SCHEDULED
[18:28:20.894] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:23:0) after 6m6s
[18:34:26.916] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:23:0)
[18:34:27.190] Build status: SCHEDULED
[18:34:27.190] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:24:0) after 8m10s
[18:42:37.260] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:24:0)
[18:42:37.322] Build status: SCHEDULED
[18:42:37.322] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:25:0) after 2m4s
[18:44:41.343] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:25:0)
[18:44:41.384] Build status: SCHEDULED
[18:44:41.384] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:26:0) after 9m21s
[18:52:24.716] Received PubSub notification, asking Buildbucket for the build status
[18:52:24.746] Build status: STARTED
[18:54:02.406] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:26:0)
[18:54:02.437] Build status: STARTED
[18:54:02.437] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:28:0) after 8m28s
[19:02:30.507] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:28:0)
[19:02:30.557] Build status: STARTED
[19:02:30.557] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:29:0) after 3m49s
[19:06:19.633] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:29:0)
[19:06:19.667] Build status: STARTED
[19:06:19.667] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:30:0) after 9m38s
[19:15:57.687] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:30:0)
[19:15:57.717] Build status: STARTED
[19:15:57.717] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:31:0) after 3m32s
[19:19:29.762] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:31:0)
[19:19:29.805] Build status: STARTED
[19:19:29.805] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:32:0) after 3m2s
[19:22:31.823] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:32:0)
[19:22:31.886] Build status: STARTED
[19:22:31.886] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Win32:8983498610726564912:33:0) after 7m9s
[19:26:13.553] Received PubSub notification, asking Buildbucket for the build status
[19:26:13.584] Build:
{
"id": "8818068548684472657",
"builder": {
"project": "v8",
"bucket": "ci.br.extended",
"builder": "V8 Win32"
},
"number": 62,
"createdBy": "project:v8",
"createTime": "2022-04-01T16:45:27.017994161Z",
"startTime": "2022-04-01T18:52:22.533219Z",
"endTime": "2022-04-01T19:26:13.260973814Z",
"updateTime": "2022-04-01T19:26:13.260973814Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "36b66b5cc99147edc594a69f4b94f7828fc94750",
"ref": "refs/branch-heads/10.0"
}
}
}
[19:26:13.584] Invocation finished in 2h40m47.898561076s with status SUCCEEDED