[12:59:44.391] New invocation is queued and will start shortly
[12:59:44.391] Triggered by user:v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com
[12:59:50.526] Starting the invocation (attempt 1)
[12:59:50.595] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/5a6a5952a0c72f6e1ad0889e9346d12a659c4839"
[12:59:50.595] Reconstructed gitiles commit from tags
[12:59:50.595] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:59:50.595] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:59:50.595] Buildbucket request:
{
"requestId": "8997102354779395168",
"builder": {
"project": "v8",
"bucket": "ci.br.beta",
"builder": "V8 Linux64 - custom snapshot - debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"buildbucket": {
"properties": {
"newest_gitiles_revision": "5a6a5952a0c72f6e1ad0889e9346d12a659c4839",
"oldest_gitiles_revision": "5a6a5952a0c72f6e1ad0889e9346d12a659c4839",
"parent_build": "https://cr-buildbucket.appspot.com/build/8831672744804637009",
"parent_buildername": "V8 Linux64 - custom snapshot - debug builder",
"parent_gn_args": [
"is_component_build = true",
"is_debug = true",
"target_cpu = \"x64\"",
"use_goma = true",
"v8_embed_script = \"test/mjsunit/mjsunit.js\"",
"v8_enable_backtrace = true",
"v8_enable_google_benchmark = true",
"v8_enable_slow_dchecks = true"
],
"parent_got_revision": "5a6a5952a0c72f6e1ad0889e9346d12a659c4839",
"parent_got_revision_cp": "refs/branch-heads/9.6@{#18}",
"parent_test_spec": {
"swarming_dimensions": {
"os": "Ubuntu-18.04"
},
"swarming_task_attrs": {
},
"tests": [
[
"mjsunit",
1,
null,
"",
[
"--no-harness"
],
{
},
{
}
]
]
},
"revision": "5a6a5952a0c72f6e1ad0889e9346d12a659c4839",
"swarm_hashes": {
"mjsunit": "eafa3f2a074733c753481486048f38bc60961f60acec64bb3a029a2b91ee634d/318"
}
},
"tags": [
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/5a6a5952a0c72f6e1ad0889e9346d12a659c4839",
"gitiles_ref:refs/branch-heads/9.6",
"parent_buildername:V8 Linux64 - custom snapshot - debug builder",
"parent_buildnumber:394",
"user_agent:recipe"
]
},
"id": "6b928564-651a-48d2-ac83-9ba53035664a",
"title": "V8 Linux64 - custom snapshot - debug builder/394"
}
]
},
"newest_gitiles_revision": "5a6a5952a0c72f6e1ad0889e9346d12a659c4839",
"oldest_gitiles_revision": "5a6a5952a0c72f6e1ad0889e9346d12a659c4839",
"parent_build": "https://cr-buildbucket.appspot.com/build/8831672744804637009",
"parent_buildername": "V8 Linux64 - custom snapshot - debug builder",
"parent_gn_args": [
"is_component_build = true",
"is_debug = true",
"target_cpu = \"x64\"",
"use_goma = true",
"v8_embed_script = \"test/mjsunit/mjsunit.js\"",
"v8_enable_backtrace = true",
"v8_enable_google_benchmark = true",
"v8_enable_slow_dchecks = true"
],
"parent_got_revision": "5a6a5952a0c72f6e1ad0889e9346d12a659c4839",
"parent_got_revision_cp": "refs/branch-heads/9.6@{#18}",
"parent_test_spec": {
"swarming_dimensions": {
"os": "Ubuntu-18.04"
},
"swarming_task_attrs": {
},
"tests": [
[
"mjsunit",
1,
null,
"",
[
"--no-harness"
],
{
},
{
}
]
]
},
"swarm_hashes": {
"mjsunit": "eafa3f2a074733c753481486048f38bc60961f60acec64bb3a029a2b91ee634d/318"
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "5a6a5952a0c72f6e1ad0889e9346d12a659c4839",
"ref": "refs/branch-heads/9.6"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8997102354779395168"
},
{
"key": "scheduler_job_id",
"value": "v8/ci.br.beta-V8 Linux64 - custom snapshot - debug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "V8 Linux64 - custom snapshot - debug builder"
},
{
"key": "parent_buildnumber",
"value": "394"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:59:51.123] Scheduled build:
{
"id": "8831672287517697249",
"builder": {
"project": "v8",
"bucket": "ci.br.beta",
"builder": "V8 Linux64 - custom snapshot - debug"
},
"number": 370,
"createdBy": "project:v8",
"createTime": "2021-11-02T12:59:50.685482993Z",
"updateTime": "2021-11-02T12:59:50.685482993Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "5a6a5952a0c72f6e1ad0889e9346d12a659c4839",
"ref": "refs/branch-heads/9.6"
}
}
}
[12:59:51.123] Task URL: https://cr-buildbucket.appspot.com/build/8831672287517697249
[12:59:51.123] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:2:0) after 5m20s
[13:05:11.145] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:2:0)
[13:05:11.177] Build status: SCHEDULED
[13:05:11.177] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:3:0) after 6m54s
[13:12:05.505] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:3:0)
[13:12:05.553] Build status: SCHEDULED
[13:12:05.553] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:4:0) after 7m21s
[13:19:26.797] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:4:0)
[13:19:26.833] Build status: SCHEDULED
[13:19:26.833] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:5:0) after 6m3s
[13:25:29.852] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:5:0)
[13:25:30.025] Build status: SCHEDULED
[13:25:30.025] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:6:0) after 6m51s
[13:32:21.187] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:6:0)
[13:32:21.221] Build status: SCHEDULED
[13:32:21.221] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:7:0) after 7m19s
[13:39:40.342] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:7:0)
[13:39:40.376] Build status: SCHEDULED
[13:39:40.376] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:8:0) after 1m21s
[13:41:01.562] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:8:0)
[13:41:01.602] Build status: SCHEDULED
[13:41:01.602] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:9:0) after 9m22s
[13:50:23.610] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:9:0)
[13:50:23.663] Build status: SCHEDULED
[13:50:23.663] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:10:0) after 8m38s
[13:59:01.713] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:10:0)
[13:59:01.762] Build status: SCHEDULED
[13:59:01.763] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:11:0) after 5m20s
[14:04:21.832] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:11:0)
[14:04:21.863] Build status: SCHEDULED
[14:04:21.863] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:12:0) after 6m27s
[14:10:49.025] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:12:0)
[14:10:49.066] Build status: SCHEDULED
[14:10:49.066] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:13:0) after 9m52s
[14:20:41.093] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:13:0)
[14:20:41.123] Build status: SCHEDULED
[14:20:41.123] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:14:0) after 7m47s
[14:28:28.287] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:14:0)
[14:28:28.355] Build status: SCHEDULED
[14:28:28.355] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:15:0) after 9m24s
[14:37:52.381] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:15:0)
[14:37:52.424] Build status: SCHEDULED
[14:37:52.424] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:16:0) after 6m2s
[14:43:54.565] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:16:0)
[14:43:54.597] Build status: SCHEDULED
[14:43:54.597] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:17:0) after 7m39s
[14:51:33.624] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:17:0)
[14:51:33.692] Build status: SCHEDULED
[14:51:33.692] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:18:0) after 1m39s
[14:53:12.738] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:18:0)
[14:53:12.772] Build status: SCHEDULED
[14:53:12.772] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:19:0) after 2m27s
[14:55:39.804] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:19:0)
[14:55:39.846] Build status: SCHEDULED
[14:55:39.846] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:20:0) after 9m49s
[15:05:28.878] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:20:0)
[15:05:29.071] Build status: SCHEDULED
[15:05:29.071] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:21:0) after 9m35s
[15:15:04.193] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:21:0)
[15:15:04.292] Build status: SCHEDULED
[15:15:04.292] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:22:0) after 9m52s
[15:24:56.312] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:22:0)
[15:24:56.369] Build status: SCHEDULED
[15:24:56.369] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:23:0) after 1m44s
[15:26:40.405] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:23:0)
[15:26:40.456] Build status: SCHEDULED
[15:26:40.456] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:24:0) after 5m3s
[15:31:43.514] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:24:0)
[15:31:43.549] Build status: SCHEDULED
[15:31:43.549] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:25:0) after 2m21s
[15:34:04.708] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:25:0)
[15:34:04.790] Build status: SCHEDULED
[15:34:04.790] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:26:0) after 1m7s
[15:35:11.810] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:26:0)
[15:35:11.863] Build status: SCHEDULED
[15:35:11.863] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:27:0) after 3m16s
[15:38:28.076] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:27:0)
[15:38:28.139] Build status: SCHEDULED
[15:38:28.139] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:28:0) after 2m45s
[15:41:13.160] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:28:0)
[15:41:13.198] Build status: SCHEDULED
[15:41:13.198] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:29:0) after 1m52s
[15:43:05.300] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:29:0)
[15:43:05.339] Build status: SCHEDULED
[15:43:05.339] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:30:0) after 6m58s
[15:50:03.364] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:30:0)
[15:50:03.407] Build status: SCHEDULED
[15:50:03.408] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:31:0) after 8m15s
[15:58:18.562] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:31:0)
[15:58:18.673] Build status: SCHEDULED
[15:58:18.673] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:32:0) after 5m35s
[16:03:53.699] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:32:0)
[16:03:53.760] Build status: SCHEDULED
[16:03:53.760] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:33:0) after 3m58s
[16:07:51.787] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:33:0)
[16:07:51.818] Build status: SCHEDULED
[16:07:51.818] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:34:0) after 3m58s
[16:11:49.841] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:34:0)
[16:11:49.875] Build status: SCHEDULED
[16:11:49.875] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:35:0) after 9m20s
[16:21:09.969] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:35:0)
[16:21:10.005] Build status: SCHEDULED
[16:21:10.005] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:36:0) after 1m39s
[16:22:49.110] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:36:0)
[16:22:49.151] Build status: SCHEDULED
[16:22:49.151] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:37:0) after 8m58s
[16:31:47.338] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:37:0)
[16:31:47.440] Build status: SCHEDULED
[16:31:47.440] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:38:0) after 7m54s
[16:37:39.402] Received PubSub notification, asking Buildbucket for the build status
[16:37:39.455] Build status: STARTED
[16:39:41.461] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:38:0)
[16:39:41.494] Build status: STARTED
[16:39:41.494] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:40:0) after 6m16s
[16:45:57.567] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:40:0)
[16:45:57.602] Build status: STARTED
[16:45:57.602] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:41:0) after 6m31s
[16:52:28.745] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:41:0)
[16:52:28.819] Build status: STARTED
[16:52:28.819] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:42:0) after 1m29s
[16:53:57.835] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:42:0)
[16:53:57.871] Build status: STARTED
[16:53:57.871] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:43:0) after 6m49s
[17:00:46.977] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:43:0)
[17:00:47.006] Build status: STARTED
[17:00:47.006] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:44:0) after 1m17s
[17:02:04.030] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:44:0)
[17:02:04.061] Build status: STARTED
[17:02:04.061] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:8997102354779395168:45:0) after 5m16s
[17:02:32.281] Received PubSub notification, asking Buildbucket for the build status
[17:02:32.315] Build:
{
"id": "8831672287517697249",
"builder": {
"project": "v8",
"bucket": "ci.br.beta",
"builder": "V8 Linux64 - custom snapshot - debug"
},
"number": 370,
"createdBy": "project:v8",
"createTime": "2021-11-02T12:59:50.685482993Z",
"startTime": "2021-11-02T16:37:38.183150Z",
"endTime": "2021-11-02T17:02:30.628061964Z",
"updateTime": "2021-11-02T17:02:30.628061964Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "5a6a5952a0c72f6e1ad0889e9346d12a659c4839",
"ref": "refs/branch-heads/9.6"
}
}
}
[17:02:32.315] Invocation finished in 4h2m47.938707405s with status SUCCEEDED