[12:09:19.094] New invocation is queued and will start shortly
[12:09:19.094] Triggered by user:v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com
[12:09:20.417] Starting the invocation (attempt 1)
[12:09:20.488] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:09:20.488] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:09:20.489] Buildbucket request:
{
"bucket": "luci.v8.ci.br.beta",
"client_operation_id": "9030082822802783328",
"parameters_json": "{\"builder_name\":\"V8 Linux64 - custom snapshot - debug\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"newest_gitiles_revision\":\"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\",\"oldest_gitiles_revision\":\"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\",\"parent_build_environment\":{\"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_slow_dchecks = true\"},\"parent_buildername\":\"V8 Linux64 - custom snapshot - debug builder\",\"parent_got_revision\":\"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\",\"parent_got_revision_cp\":\"refs/branch-heads/8.7@{#31}\",\"parent_got_swarming_client_revision\":\"44c13d73156581ea09b9389001e58c23a4b8d70a\",\"parent_test_spec\":{\"swarming_dimensions\":{\"os\":\"Ubuntu-16.04\"},\"swarming_task_attrs\":{},\"tests\":[[\"mjsunit\",1,null,\"\",[\"--no-harness\"],{},{}]]},\"revision\":\"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\",\"swarm_hashes\":{\"mjsunit\":\"86160f8506d47abd94924ecf92c947ab2701e715\"}},\"tags\":[\"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\",\"gitiles_ref:refs/branch-heads/8.7\",\"parent_buildername:V8 Linux64 - custom snapshot - debug builder\",\"parent_buildnumber:86\",\"user_agent:recipe\"]},\"id\":\"9da5cdcc-3f3e-491c-8a1b-715614de0277\",\"title\":\"V8 Linux64 - custom snapshot - debug builder/86\"}]},\"newest_gitiles_revision\":\"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\",\"oldest_gitiles_revision\":\"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\",\"parent_build_environment\":{\"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_slow_dchecks = true\"},\"parent_buildername\":\"V8 Linux64 - custom snapshot - debug builder\",\"parent_got_revision\":\"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\",\"parent_got_revision_cp\":\"refs/branch-heads/8.7@{#31}\",\"parent_got_swarming_client_revision\":\"44c13d73156581ea09b9389001e58c23a4b8d70a\",\"parent_test_spec\":{\"swarming_dimensions\":{\"os\":\"Ubuntu-16.04\"},\"swarming_task_attrs\":{},\"tests\":[[\"mjsunit\",1,null,\"\",[\"--no-harness\"],{},{}]]},\"revision\":\"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\",\"swarm_hashes\":{\"mjsunit\":\"86160f8506d47abd94924ecf92c947ab2701e715\"}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:V8 Linux64 - custom snapshot - debug",
"scheduler_invocation_id:9030082822802783328",
"scheduler_job_id:v8/ci.br.beta-V8 Linux64 - custom snapshot - debug",
"user_agent:luci-scheduler",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/146bd99e762bd93f6c5520bb94f02b8ab0aa8d42",
"gitiles_ref:refs/branch-heads/8.7",
"parent_buildername:V8 Linux64 - custom snapshot - debug builder",
"parent_buildnumber:86",
"user_agent:recipe"
]
}
[12:09:23.787] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci.br.beta",
"canary_preference": "PROD",
"created_by": "project:v8",
"created_ts": "1604405360571240",
"id": "8864652760592307616",
"parameters_json": "{\"builder_name\": \"V8 Linux64 - custom snapshot - debug\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"newest_gitiles_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"oldest_gitiles_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"parent_build_environment\": {\"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_slow_dchecks = true\"}, \"parent_buildername\": \"V8 Linux64 - custom snapshot - debug builder\", \"parent_got_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"parent_got_revision_cp\": \"refs/branch-heads/8.7@{#31}\", \"parent_got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"parent_test_spec\": {\"swarming_dimensions\": {\"os\": \"Ubuntu-16.04\"}, \"swarming_task_attrs\": {}, \"tests\": [[\"mjsunit\", 1.0, null, \"\", [\"--no-harness\"], {}, {}]]}, \"revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"swarm_hashes\": {\"mjsunit\": \"86160f8506d47abd94924ecf92c947ab2701e715\"}}, \"tags\": [\"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"gitiles_ref:refs/branch-heads/8.7\", \"parent_buildername:V8 Linux64 - custom snapshot - debug builder\", \"parent_buildnumber:86\", \"user_agent:recipe\"]}, \"id\": \"9da5cdcc-3f3e-491c-8a1b-715614de0277\", \"title\": \"V8 Linux64 - custom snapshot - debug builder/86\"}]}, \"newest_gitiles_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"oldest_gitiles_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"parent_build_environment\": {\"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_slow_dchecks = true\"}, \"parent_buildername\": \"V8 Linux64 - custom snapshot - debug builder\", \"parent_got_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"parent_got_revision_cp\": \"refs/branch-heads/8.7@{#31}\", \"parent_got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"parent_test_spec\": {\"swarming_dimensions\": {\"os\": \"Ubuntu-16.04\"}, \"swarming_task_attrs\": {}, \"tests\": [[\"mjsunit\", 1.0, null, \"\", [\"--no-harness\"], {}, {}]]}, \"revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"swarm_hashes\": {\"mjsunit\": \"86160f8506d47abd94924ecf92c947ab2701e715\"}}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604405363166327",
"tags": [
"build_address:luci.v8.ci.br.beta/V8 Linux64 - custom snapshot - debug/83",
"builder:V8 Linux64 - custom snapshot - debug",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/146bd99e762bd93f6c5520bb94f02b8ab0aa8d42",
"gitiles_ref:refs/branch-heads/8.7",
"parent_buildername:V8 Linux64 - custom snapshot - debug builder",
"parent_buildnumber:86",
"scheduler_invocation_id:9030082822802783328",
"scheduler_job_id:v8/ci.br.beta-V8 Linux64 - custom snapshot - debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8864652760592307616/+/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": "1604405363166508",
"url": "https://ci.chromium.org/b/8864652760592307616",
"utcnow_ts": "1604405363776017"
}
}
[12:09:23.787] Task URL: https://ci.chromium.org/b/8864652760592307616
[12:09:23.787] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:2:0) after 1m0s
[12:10:23.839] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:2:0)
[12:10:23.839] Timer tick, asking Buildbucket for the build status
[12:10:24.314] Build 8864652760592307616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:10:24.314] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:3:0) after 1m0s
[12:11:24.343] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:3:0)
[12:11:24.343] Timer tick, asking Buildbucket for the build status
[12:11:25.527] Build 8864652760592307616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:11:25.527] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:4:0) after 1m0s
[12:12:25.518] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:4:0)
[12:12:25.518] Timer tick, asking Buildbucket for the build status
[12:12:25.745] Build 8864652760592307616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:12:25.745] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:5:0) after 1m0s
[12:13:25.787] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:5:0)
[12:13:25.787] Timer tick, asking Buildbucket for the build status
[12:13:25.928] Build 8864652760592307616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:13:25.928] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:6:0) after 1m0s
[12:14:25.964] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:6:0)
[12:14:25.964] Timer tick, asking Buildbucket for the build status
[12:14:26.106] Build 8864652760592307616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:14:26.106] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:7:0) after 1m0s
[12:15:26.180] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:7:0)
[12:15:26.180] Timer tick, asking Buildbucket for the build status
[12:15:26.931] Build 8864652760592307616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:15:26.931] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:8:0) after 1m0s
[12:16:27.034] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:8:0)
[12:16:27.034] Timer tick, asking Buildbucket for the build status
[12:16:27.157] Build 8864652760592307616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:16:27.157] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:9:0) after 1m0s
[12:17:27.145] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:9:0)
[12:17:27.145] Timer tick, asking Buildbucket for the build status
[12:17:27.265] Build 8864652760592307616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:17:27.265] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:10:0) after 1m0s
[12:18:27.285] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:10:0)
[12:18:27.285] Timer tick, asking Buildbucket for the build status
[12:18:27.514] Build 8864652760592307616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:18:27.514] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:11:0) after 1m0s
[12:19:27.597] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:11:0)
[12:19:27.597] Timer tick, asking Buildbucket for the build status
[12:19:27.710] Build 8864652760592307616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:19:27.710] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:12:0) after 1m0s
[12:20:27.785] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:12:0)
[12:20:27.785] Timer tick, asking Buildbucket for the build status
[12:20:27.983] Build 8864652760592307616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:20:27.983] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:13:0) after 1m0s
[12:21:17.270] Received PubSub notification, asking Buildbucket for the build status
[12:21:17.371] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:28.022] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:13:0)
[12:21:28.022] Timer tick, asking Buildbucket for the build status
[12:21:28.237] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:28.237] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:15:0) after 1m0s
[12:22:28.275] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:15:0)
[12:22:28.275] Timer tick, asking Buildbucket for the build status
[12:22:28.581] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:28.581] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:16:0) after 1m0s
[12:23:28.634] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:16:0)
[12:23:28.634] Timer tick, asking Buildbucket for the build status
[12:23:28.783] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:28.783] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:17:0) after 1m0s
[12:24:28.838] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:17:0)
[12:24:28.838] Timer tick, asking Buildbucket for the build status
[12:24:28.945] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:28.945] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:18:0) after 1m0s
[12:25:28.981] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:18:0)
[12:25:28.981] Timer tick, asking Buildbucket for the build status
[12:25:29.846] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:29.846] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:19:0) after 1m0s
[12:26:29.928] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:19:0)
[12:26:29.928] Timer tick, asking Buildbucket for the build status
[12:26:30.210] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:30.210] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:20:0) after 1m0s
[12:27:30.915] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:20:0)
[12:27:30.915] Timer tick, asking Buildbucket for the build status
[12:27:31.375] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:31.375] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:21:0) after 1m0s
[12:28:31.397] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:21:0)
[12:28:31.397] Timer tick, asking Buildbucket for the build status
[12:28:31.560] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:31.560] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:22:0) after 1m0s
[12:29:31.613] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:22:0)
[12:29:31.613] Timer tick, asking Buildbucket for the build status
[12:29:32.071] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:32.071] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:23:0) after 1m0s
[12:30:32.120] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:23:0)
[12:30:32.120] Timer tick, asking Buildbucket for the build status
[12:30:32.648] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:32.649] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:24:0) after 1m0s
[12:31:32.776] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:24:0)
[12:31:32.776] Timer tick, asking Buildbucket for the build status
[12:31:32.894] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:32.894] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:25:0) after 1m0s
[12:32:32.936] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:25:0)
[12:32:32.937] Timer tick, asking Buildbucket for the build status
[12:32:33.046] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:33.046] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:26:0) after 1m0s
[12:33:33.134] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:26:0)
[12:33:33.134] Timer tick, asking Buildbucket for the build status
[12:33:33.320] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:33.320] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:27:0) after 1m0s
[12:34:33.474] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:27:0)
[12:34:33.474] Timer tick, asking Buildbucket for the build status
[12:34:33.600] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:33.600] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:28:0) after 1m0s
[12:35:33.603] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:28:0)
[12:35:33.603] Timer tick, asking Buildbucket for the build status
[12:35:33.749] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:33.749] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:29:0) after 1m0s
[12:36:33.816] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:29:0)
[12:36:33.816] Timer tick, asking Buildbucket for the build status
[12:36:34.120] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:34.120] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:30:0) after 1m0s
[12:37:34.169] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:30:0)
[12:37:34.169] Timer tick, asking Buildbucket for the build status
[12:37:34.286] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:34.286] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:31:0) after 1m0s
[12:38:34.333] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:31:0)
[12:38:34.333] Timer tick, asking Buildbucket for the build status
[12:38:34.424] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:34.424] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:32:0) after 1m0s
[12:39:34.491] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:32:0)
[12:39:34.492] Timer tick, asking Buildbucket for the build status
[12:39:35.854] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:35.855] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:33:0) after 1m0s
[12:40:35.901] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:33:0)
[12:40:35.901] Timer tick, asking Buildbucket for the build status
[12:40:35.993] Build 8864652760592307616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:35.993] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux64 - custom snapshot - debug:9030082822802783328:34:0) after 1m0s
[12:40:54.184] Received PubSub notification, asking Buildbucket for the build status
[12:40:54.277] Build 8864652760592307616: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:40:54.277] Buildbucket build:
{
"build": {
"bucket": "luci.v8.ci.br.beta",
"canary_preference": "PROD",
"completed_ts": "1604407253019980",
"created_by": "project:v8",
"created_ts": "1604405360571240",
"id": "8864652760592307616",
"parameters_json": "{\"builder_name\": \"V8 Linux64 - custom snapshot - debug\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"newest_gitiles_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"oldest_gitiles_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"parent_build_environment\": {\"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_slow_dchecks = true\"}, \"parent_buildername\": \"V8 Linux64 - custom snapshot - debug builder\", \"parent_got_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"parent_got_revision_cp\": \"refs/branch-heads/8.7@{#31}\", \"parent_got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"parent_test_spec\": {\"swarming_dimensions\": {\"os\": \"Ubuntu-16.04\"}, \"swarming_task_attrs\": {}, \"tests\": [[\"mjsunit\", 1.0, null, \"\", [\"--no-harness\"], {}, {}]]}, \"revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"swarm_hashes\": {\"mjsunit\": \"86160f8506d47abd94924ecf92c947ab2701e715\"}}, \"tags\": [\"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"gitiles_ref:refs/branch-heads/8.7\", \"parent_buildername:V8 Linux64 - custom snapshot - debug builder\", \"parent_buildnumber:86\", \"user_agent:recipe\"]}, \"id\": \"9da5cdcc-3f3e-491c-8a1b-715614de0277\", \"title\": \"V8 Linux64 - custom snapshot - debug builder/86\"}]}, \"newest_gitiles_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"oldest_gitiles_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"parent_build_environment\": {\"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_slow_dchecks = true\"}, \"parent_buildername\": \"V8 Linux64 - custom snapshot - debug builder\", \"parent_got_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"parent_got_revision_cp\": \"refs/branch-heads/8.7@{#31}\", \"parent_got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"parent_test_spec\": {\"swarming_dimensions\": {\"os\": \"Ubuntu-16.04\"}, \"swarming_task_attrs\": {}, \"tests\": [[\"mjsunit\", 1.0, null, \"\", [\"--no-harness\"], {}, {}]]}, \"revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"swarm_hashes\": {\"mjsunit\": \"86160f8506d47abd94924ecf92c947ab2701e715\"}}}",
"project": "v8",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com/\"}, \"$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\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"oldest_gitiles_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"parent_build_environment\": {\"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_slow_dchecks = true\"}, \"parent_buildername\": \"V8 Linux64 - custom snapshot - debug builder\", \"parent_got_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"parent_got_revision_cp\": \"refs/branch-heads/8.7@{#31}\", \"parent_got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"parent_test_spec\": {\"swarming_dimensions\": {\"os\": \"Ubuntu-16.04\"}, \"swarming_task_attrs\": {}, \"tests\": [[\"mjsunit\", 1.0, null, \"\", [\"--no-harness\"], {}, {}]]}, \"revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"swarm_hashes\": {\"mjsunit\": \"86160f8506d47abd94924ecf92c947ab2701e715\"}}, \"tags\": [\"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"gitiles_ref:refs/branch-heads/8.7\", \"parent_buildername:V8 Linux64 - custom snapshot - debug builder\", \"parent_buildnumber:86\", \"user_agent:recipe\"]}, \"id\": \"9da5cdcc-3f3e-491c-8a1b-715614de0277\", \"title\": \"V8 Linux64 - custom snapshot - debug builder/86\"}]}, \"bot_id\": \"luci-v8-ci-slim-14-pumj\", \"builder_group\": \"client.v8\", \"buildername\": \"V8 Linux64 - custom snapshot - debug\", \"buildnumber\": 83, \"got_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"got_revision_cp\": \"refs/branch-heads/8.7@{#31}\", \"newest_gitiles_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"oldest_gitiles_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"parent_build_environment\": {\"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_slow_dchecks = true\"}, \"parent_buildername\": \"V8 Linux64 - custom snapshot - debug builder\", \"parent_got_revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"parent_got_revision_cp\": \"refs/branch-heads/8.7@{#31}\", \"parent_got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"parent_test_spec\": {\"swarming_dimensions\": {\"os\": \"Ubuntu-16.04\"}, \"swarming_task_attrs\": {}, \"tests\": [[\"mjsunit\", 1.0, null, \"\", [\"--no-harness\"], {}, {}]]}, \"path_config\": \"generic\", \"recipe\": \"v8\", \"revision\": \"146bd99e762bd93f6c5520bb94f02b8ab0aa8d42\", \"swarm_hashes\": {\"mjsunit\": \"86160f8506d47abd94924ecf92c947ab2701e715\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\", \"v8_builder_cache_nowait\", \"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-14-pumj\"], \"image\": [\"chrome-xenial-20110100-7e97731f9b6\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"g1-small\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.v8.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604406075057710",
"status": "COMPLETED",
"status_changed_ts": "1604407253800281",
"tags": [
"build_address:luci.v8.ci.br.beta/V8 Linux64 - custom snapshot - debug/83",
"builder:V8 Linux64 - custom snapshot - debug",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/146bd99e762bd93f6c5520bb94f02b8ab0aa8d42",
"gitiles_ref:refs/branch-heads/8.7",
"parent_buildername:V8 Linux64 - custom snapshot - debug builder",
"parent_buildnumber:86",
"scheduler_invocation_id:9030082822802783328",
"scheduler_job_id:v8/ci.br.beta-V8 Linux64 - custom snapshot - debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8864652760592307616/+/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:4fa6d494c0126410",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1604407253942747",
"url": "https://ci.chromium.org/b/8864652760592307616",
"utcnow_ts": "1604407254264147"
}
}
[12:40:54.277] Invocation finished in 31m35.19828923s with status SUCCEEDED