[22:52:14.792] New invocation is queued and will start shortly
[22:52:14.792] Triggered by user:v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com
[22:52:16.835] Starting the invocation (attempt 1)
[22:52:16.880] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:52:16.880] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:52:16.881] Buildbucket request:
{
"bucket": "luci.v8.ci",
"client_operation_id": "9053235196651952256",
"parameters_json": "{\"builder_name\":\"V8 Linux64 - debug - perfetto\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"newest_gitiles_revision\":\"96dda2fac221296f24ab618064782190fdca7cd9\",\"oldest_gitiles_revision\":\"96dda2fac221296f24ab618064782190fdca7cd9\",\"parent_build_config\":\"Debug\",\"parent_build_environment\":{\"gn_args\":\"is_component_build = true is_debug = true target_cpu = \\\"x64\\\" use_goma = true v8_enable_backtrace = true v8_enable_slow_dchecks = true v8_use_perfetto = true\"},\"parent_buildername\":\"V8 Linux64 - debug - perfetto - builder\",\"parent_got_revision\":\"96dda2fac221296f24ab618064782190fdca7cd9\",\"parent_got_revision_cp\":\"refs/heads/master@{#66395}\",\"parent_got_swarming_client_revision\":\"0ac28472d073a515124c85b128c4378a914e0a4d\",\"parent_test_spec\":{\"swarming_dimensions\":{\"os\":\"Ubuntu-16.04\"},\"swarming_task_attrs\":{\"expiration\":14400,\"hard_timeout\":3600,\"priority\":35},\"tests\":[[\"v8testing\",2,null,\"\",[],{},{}]]},\"revision\":\"96dda2fac221296f24ab618064782190fdca7cd9\",\"swarm_hashes\":{\"bot_default\":\"43aedea13480287ed66cecd1336858596ccc2048\"}},\"tags\":[\"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/96dda2fac221296f24ab618064782190fdca7cd9\",\"gitiles_ref:refs/heads/master\",\"parent_buildername:V8 Linux64 - debug - perfetto - builder\",\"parent_buildnumber:5201\",\"user_agent:recipe\"]},\"id\":\"66a37d89-4e25-45ba-86f9-7c139a2ac213\",\"title\":\"V8 Linux64 - debug - perfetto - builder/5201\"}]},\"newest_gitiles_revision\":\"96dda2fac221296f24ab618064782190fdca7cd9\",\"oldest_gitiles_revision\":\"96dda2fac221296f24ab618064782190fdca7cd9\",\"parent_build_config\":\"Debug\",\"parent_build_environment\":{\"gn_args\":\"is_component_build = true is_debug = true target_cpu = \\\"x64\\\" use_goma = true v8_enable_backtrace = true v8_enable_slow_dchecks = true v8_use_perfetto = true\"},\"parent_buildername\":\"V8 Linux64 - debug - perfetto - builder\",\"parent_got_revision\":\"96dda2fac221296f24ab618064782190fdca7cd9\",\"parent_got_revision_cp\":\"refs/heads/master@{#66395}\",\"parent_got_swarming_client_revision\":\"0ac28472d073a515124c85b128c4378a914e0a4d\",\"parent_test_spec\":{\"swarming_dimensions\":{\"os\":\"Ubuntu-16.04\"},\"swarming_task_attrs\":{\"expiration\":14400,\"hard_timeout\":3600,\"priority\":35},\"tests\":[[\"v8testing\",2,null,\"\",[],{},{}]]},\"revision\":\"96dda2fac221296f24ab618064782190fdca7cd9\",\"swarm_hashes\":{\"bot_default\":\"43aedea13480287ed66cecd1336858596ccc2048\"}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:V8 Linux64 - debug - perfetto",
"scheduler_invocation_id:9053235196651952256",
"scheduler_job_id:v8/V8 Linux64 - debug - perfetto",
"user_agent:luci-scheduler",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/96dda2fac221296f24ab618064782190fdca7cd9",
"gitiles_ref:refs/heads/master",
"parent_buildername:V8 Linux64 - debug - perfetto - builder",
"parent_buildnumber:5201",
"user_agent:recipe"
]
}
[22:52:19.318] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"created_by": "project:v8",
"created_ts": "1582325537064162",
"id": "8887805133606296208",
"parameters_json": "{\"builder_name\": \"V8 Linux64 - debug - perfetto\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"newest_gitiles_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"oldest_gitiles_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"parent_build_config\": \"Debug\", \"parent_build_environment\": {\"gn_args\": \"is_component_build = true is_debug = true target_cpu = \\\"x64\\\" use_goma = true v8_enable_backtrace = true v8_enable_slow_dchecks = true v8_use_perfetto = true\"}, \"parent_buildername\": \"V8 Linux64 - debug - perfetto - builder\", \"parent_got_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"parent_got_revision_cp\": \"refs/heads/master@{#66395}\", \"parent_got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"parent_test_spec\": {\"swarming_dimensions\": {\"os\": \"Ubuntu-16.04\"}, \"swarming_task_attrs\": {\"expiration\": 14400.0, \"hard_timeout\": 3600.0, \"priority\": 35.0}, \"tests\": [[\"v8testing\", 2.0, null, \"\", [], {}, {}]]}, \"revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"swarm_hashes\": {\"bot_default\": \"43aedea13480287ed66cecd1336858596ccc2048\"}}, \"tags\": [\"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/96dda2fac221296f24ab618064782190fdca7cd9\", \"gitiles_ref:refs/heads/master\", \"parent_buildername:V8 Linux64 - debug - perfetto - builder\", \"parent_buildnumber:5201\", \"user_agent:recipe\"]}, \"id\": \"66a37d89-4e25-45ba-86f9-7c139a2ac213\", \"title\": \"V8 Linux64 - debug - perfetto - builder/5201\"}]}, \"newest_gitiles_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"oldest_gitiles_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"parent_build_config\": \"Debug\", \"parent_build_environment\": {\"gn_args\": \"is_component_build = true is_debug = true target_cpu = \\\"x64\\\" use_goma = true v8_enable_backtrace = true v8_enable_slow_dchecks = true v8_use_perfetto = true\"}, \"parent_buildername\": \"V8 Linux64 - debug - perfetto - builder\", \"parent_got_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"parent_got_revision_cp\": \"refs/heads/master@{#66395}\", \"parent_got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"parent_test_spec\": {\"swarming_dimensions\": {\"os\": \"Ubuntu-16.04\"}, \"swarming_task_attrs\": {\"expiration\": 14400.0, \"hard_timeout\": 3600.0, \"priority\": 35.0}, \"tests\": [[\"v8testing\", 2.0, null, \"\", [], {}, {}]]}, \"revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"swarm_hashes\": {\"bot_default\": \"43aedea13480287ed66cecd1336858596ccc2048\"}}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582325539087054",
"tags": [
"build_address:luci.v8.ci/V8 Linux64 - debug - perfetto/3959",
"builder:V8 Linux64 - debug - perfetto",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/96dda2fac221296f24ab618064782190fdca7cd9",
"gitiles_ref:refs/heads/master",
"parent_buildername:V8 Linux64 - debug - perfetto - builder",
"parent_buildnumber:5201",
"scheduler_invocation_id:9053235196651952256",
"scheduler_job_id:v8/V8 Linux64 - debug - perfetto",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8887805133606296208/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:recipe_name:v8",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1582325539087163",
"url": "https://ci.chromium.org/b/8887805133606296208",
"utcnow_ts": "1582325539310796"
}
}
[22:52:19.318] Task URL: https://ci.chromium.org/b/8887805133606296208
[22:52:19.318] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:2:0) after 1m0s
[22:53:19.527] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:2:0)
[22:53:19.527] Timer tick, asking Buildbucket for the build status
[22:53:19.726] Build 8887805133606296208: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:19.726] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:3:0) after 1m0s
[22:54:19.802] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:3:0)
[22:54:19.802] Timer tick, asking Buildbucket for the build status
[22:54:19.897] Build 8887805133606296208: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:19.897] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:4:0) after 1m0s
[22:55:20.105] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:4:0)
[22:55:20.105] Timer tick, asking Buildbucket for the build status
[22:55:20.275] Build 8887805133606296208: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:20.275] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:5:0) after 1m0s
[22:56:20.295] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:5:0)
[22:56:20.295] Timer tick, asking Buildbucket for the build status
[22:56:21.172] Build 8887805133606296208: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:21.172] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:6:0) after 1m0s
[22:56:36.610] Received PubSub notification, asking Buildbucket for the build status
[22:56:39.148] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:21.353] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:6:0)
[22:57:21.353] Timer tick, asking Buildbucket for the build status
[22:57:21.534] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:21.534] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:8:0) after 1m0s
[22:58:21.635] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:8:0)
[22:58:21.635] Timer tick, asking Buildbucket for the build status
[22:58:21.728] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:21.728] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:9:0) after 1m0s
[22:59:21.860] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:9:0)
[22:59:21.860] Timer tick, asking Buildbucket for the build status
[22:59:22.013] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:22.013] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:10:0) after 1m0s
[23:00:22.201] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:10:0)
[23:00:22.201] Timer tick, asking Buildbucket for the build status
[23:00:22.453] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:22.453] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:11:0) after 1m0s
[23:01:22.579] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:11:0)
[23:01:22.579] Timer tick, asking Buildbucket for the build status
[23:01:24.554] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:24.554] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:12:0) after 1m0s
[23:02:24.601] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:12:0)
[23:02:24.601] Timer tick, asking Buildbucket for the build status
[23:02:25.057] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:25.057] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:13:0) after 1m0s
[23:03:25.076] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:13:0)
[23:03:25.076] Timer tick, asking Buildbucket for the build status
[23:03:27.661] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:27.661] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:14:0) after 1m0s
[23:04:27.848] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:14:0)
[23:04:27.848] Timer tick, asking Buildbucket for the build status
[23:04:28.543] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:28.543] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:15:0) after 1m0s
[23:05:28.674] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:15:0)
[23:05:28.674] Timer tick, asking Buildbucket for the build status
[23:05:28.872] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:28.872] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:16:0) after 1m0s
[23:06:28.980] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:16:0)
[23:06:28.980] Timer tick, asking Buildbucket for the build status
[23:06:30.049] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:30.049] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:17:0) after 1m0s
[23:07:30.180] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:17:0)
[23:07:30.180] Timer tick, asking Buildbucket for the build status
[23:07:30.317] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:30.317] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:18:0) after 1m0s
[23:08:30.398] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:18:0)
[23:08:30.398] Timer tick, asking Buildbucket for the build status
[23:08:30.685] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:30.685] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:19:0) after 1m0s
[23:09:30.664] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:19:0)
[23:09:30.664] Timer tick, asking Buildbucket for the build status
[23:09:30.875] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:30.875] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:20:0) after 1m0s
[23:10:31.277] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:20:0)
[23:10:31.277] Timer tick, asking Buildbucket for the build status
[23:10:31.967] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:31.967] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:21:0) after 1m0s
[23:11:31.957] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:21:0)
[23:11:31.957] Timer tick, asking Buildbucket for the build status
[23:11:33.607] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:33.607] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:22:0) after 1m0s
[23:12:33.731] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:22:0)
[23:12:33.731] Timer tick, asking Buildbucket for the build status
[23:12:33.987] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:33.987] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:23:0) after 1m0s
[23:13:34.055] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:23:0)
[23:13:34.055] Timer tick, asking Buildbucket for the build status
[23:13:34.331] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:34.331] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:24:0) after 1m0s
[23:14:34.431] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:24:0)
[23:14:34.431] Timer tick, asking Buildbucket for the build status
[23:14:34.561] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:34.561] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:25:0) after 1m0s
[23:15:34.650] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:25:0)
[23:15:34.650] Timer tick, asking Buildbucket for the build status
[23:15:34.824] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:34.824] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:26:0) after 1m0s
[23:16:34.865] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:26:0)
[23:16:34.865] Timer tick, asking Buildbucket for the build status
[23:16:35.563] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:35.563] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:27:0) after 1m0s
[23:17:35.678] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:27:0)
[23:17:35.678] Timer tick, asking Buildbucket for the build status
[23:17:35.902] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:35.902] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:28:0) after 1m0s
[23:18:36.024] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:28:0)
[23:18:36.024] Timer tick, asking Buildbucket for the build status
[23:18:36.205] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:36.205] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:29:0) after 1m0s
[23:19:36.221] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:29:0)
[23:19:36.221] Timer tick, asking Buildbucket for the build status
[23:19:36.552] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:36.552] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:30:0) after 1m0s
[23:20:36.590] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:30:0)
[23:20:36.590] Timer tick, asking Buildbucket for the build status
[23:20:36.893] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:36.893] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:31:0) after 1m0s
[23:21:36.981] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:31:0)
[23:21:36.981] Timer tick, asking Buildbucket for the build status
[23:21:37.942] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:37.942] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:32:0) after 1m0s
[23:22:38.077] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:32:0)
[23:22:38.077] Timer tick, asking Buildbucket for the build status
[23:22:38.798] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:38.798] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:33:0) after 1m0s
[23:23:38.868] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:33:0)
[23:23:38.868] Timer tick, asking Buildbucket for the build status
[23:23:40.110] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:40.110] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:34:0) after 1m0s
[23:24:40.137] Handling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:34:0)
[23:24:40.137] Timer tick, asking Buildbucket for the build status
[23:24:40.889] Build 8887805133606296208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:40.889] Scheduling timer "check-buildbucket-build-status" (v8/V8 Linux64 - debug - perfetto:9053235196651952256:35:0) after 1m0s
[23:25:23.540] Received PubSub notification, asking Buildbucket for the build status
[23:25:23.710] Build 8887805133606296208: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:25:23.710] Buildbucket build:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"completed_ts": "1582327522156833",
"created_by": "project:v8",
"created_ts": "1582325537064162",
"id": "8887805133606296208",
"parameters_json": "{\"builder_name\": \"V8 Linux64 - debug - perfetto\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"newest_gitiles_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"oldest_gitiles_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"parent_build_config\": \"Debug\", \"parent_build_environment\": {\"gn_args\": \"is_component_build = true is_debug = true target_cpu = \\\"x64\\\" use_goma = true v8_enable_backtrace = true v8_enable_slow_dchecks = true v8_use_perfetto = true\"}, \"parent_buildername\": \"V8 Linux64 - debug - perfetto - builder\", \"parent_got_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"parent_got_revision_cp\": \"refs/heads/master@{#66395}\", \"parent_got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"parent_test_spec\": {\"swarming_dimensions\": {\"os\": \"Ubuntu-16.04\"}, \"swarming_task_attrs\": {\"expiration\": 14400.0, \"hard_timeout\": 3600.0, \"priority\": 35.0}, \"tests\": [[\"v8testing\", 2.0, null, \"\", [], {}, {}]]}, \"revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"swarm_hashes\": {\"bot_default\": \"43aedea13480287ed66cecd1336858596ccc2048\"}}, \"tags\": [\"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/96dda2fac221296f24ab618064782190fdca7cd9\", \"gitiles_ref:refs/heads/master\", \"parent_buildername:V8 Linux64 - debug - perfetto - builder\", \"parent_buildnumber:5201\", \"user_agent:recipe\"]}, \"id\": \"66a37d89-4e25-45ba-86f9-7c139a2ac213\", \"title\": \"V8 Linux64 - debug - perfetto - builder/5201\"}]}, \"newest_gitiles_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"oldest_gitiles_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"parent_build_config\": \"Debug\", \"parent_build_environment\": {\"gn_args\": \"is_component_build = true is_debug = true target_cpu = \\\"x64\\\" use_goma = true v8_enable_backtrace = true v8_enable_slow_dchecks = true v8_use_perfetto = true\"}, \"parent_buildername\": \"V8 Linux64 - debug - perfetto - builder\", \"parent_got_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"parent_got_revision_cp\": \"refs/heads/master@{#66395}\", \"parent_got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"parent_test_spec\": {\"swarming_dimensions\": {\"os\": \"Ubuntu-16.04\"}, \"swarming_task_attrs\": {\"expiration\": 14400.0, \"hard_timeout\": 3600.0, \"priority\": 35.0}, \"tests\": [[\"v8testing\", 2.0, null, \"\", [], {}, {}]]}, \"revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"swarm_hashes\": {\"bot_default\": \"43aedea13480287ed66cecd1336858596ccc2048\"}}}",
"project": "v8",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"newest_gitiles_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"oldest_gitiles_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"parent_build_config\": \"Debug\", \"parent_build_environment\": {\"gn_args\": \"is_component_build = true is_debug = true target_cpu = \\\"x64\\\" use_goma = true v8_enable_backtrace = true v8_enable_slow_dchecks = true v8_use_perfetto = true\"}, \"parent_buildername\": \"V8 Linux64 - debug - perfetto - builder\", \"parent_got_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"parent_got_revision_cp\": \"refs/heads/master@{#66395}\", \"parent_got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"parent_test_spec\": {\"swarming_dimensions\": {\"os\": \"Ubuntu-16.04\"}, \"swarming_task_attrs\": {\"expiration\": 14400.0, \"hard_timeout\": 3600.0, \"priority\": 35.0}, \"tests\": [[\"v8testing\", 2.0, null, \"\", [], {}, {}]]}, \"revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"swarm_hashes\": {\"bot_default\": \"43aedea13480287ed66cecd1336858596ccc2048\"}}, \"tags\": [\"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/96dda2fac221296f24ab618064782190fdca7cd9\", \"gitiles_ref:refs/heads/master\", \"parent_buildername:V8 Linux64 - debug - perfetto - builder\", \"parent_buildnumber:5201\", \"user_agent:recipe\"]}, \"id\": \"66a37d89-4e25-45ba-86f9-7c139a2ac213\", \"title\": \"V8 Linux64 - debug - perfetto - builder/5201\"}]}, \"bot_id\": \"luci-v8-ci-slim-20-9fpu\", \"buildername\": \"V8 Linux64 - debug - perfetto\", \"buildnumber\": 3959, \"got_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"got_revision_cp\": \"refs/heads/master@{#66395}\", \"mastername\": \"client.v8\", \"newest_gitiles_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"oldest_gitiles_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"parent_build_config\": \"Debug\", \"parent_build_environment\": {\"gn_args\": \"is_component_build = true is_debug = true target_cpu = \\\"x64\\\" use_goma = true v8_enable_backtrace = true v8_enable_slow_dchecks = true v8_use_perfetto = true\"}, \"parent_buildername\": \"V8 Linux64 - debug - perfetto - builder\", \"parent_got_revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"parent_got_revision_cp\": \"refs/heads/master@{#66395}\", \"parent_got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"parent_test_spec\": {\"swarming_dimensions\": {\"os\": \"Ubuntu-16.04\"}, \"swarming_task_attrs\": {\"expiration\": 14400.0, \"hard_timeout\": 3600.0, \"priority\": 35.0}, \"tests\": [[\"v8testing\", 2.0, null, \"\", [], {}, {}]]}, \"path_config\": \"generic\", \"recipe\": \"v8\", \"revision\": \"96dda2fac221296f24ab618064782190fdca7cd9\", \"swarm_hashes\": {\"bot_default\": \"43aedea13480287ed66cecd1336858596ccc2048\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"host_class\": [\"multibot\"], \"id\": [\"luci-v8-ci-slim-20-9fpu\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"g1-small\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.v8.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582325795752675",
"status": "COMPLETED",
"status_changed_ts": "1582327522850433",
"tags": [
"build_address:luci.v8.ci/V8 Linux64 - debug - perfetto/3959",
"builder:V8 Linux64 - debug - perfetto",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/96dda2fac221296f24ab618064782190fdca7cd9",
"gitiles_ref:refs/heads/master",
"parent_buildername:V8 Linux64 - debug - perfetto - builder",
"parent_buildnumber:5201",
"scheduler_invocation_id:9053235196651952256",
"scheduler_job_id:v8/V8 Linux64 - debug - perfetto",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8887805133606296208/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:v8",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4a82c52be05de310",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1582327523062804",
"url": "https://ci.chromium.org/b/8887805133606296208",
"utcnow_ts": "1582327523696120"
}
}
[23:25:23.710] Invocation finished in 33m8.935111896s with status SUCCEEDED