[08:52:36.774] New invocation is queued and will start shortly
[08:52:36.774] Triggered by user:v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com
[08:52:37.943] Starting the invocation (attempt 1)
[08:52:37.978] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:52:37.979] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:52:37.979] Buildbucket request:
{
"bucket": "luci.v8.ci",
"client_operation_id": "9017502220101442128",
"parameters_json": "{\"builder_name\":\"V8 NumFuzz - debug\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"newest_gitiles_revision\":\"7f0912269d17b509133389b5bc0580508bf1de6f\",\"oldest_gitiles_revision\":\"7f0912269d17b509133389b5bc0580508bf1de6f\",\"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\"},\"parent_buildername\":\"V8 Clusterfuzz Linux64 - debug builder\",\"parent_got_revision\":\"7f0912269d17b509133389b5bc0580508bf1de6f\",\"parent_got_revision_cp\":\"refs/heads/master@{#73558}\",\"parent_got_swarming_client_revision\":\"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\",\"parent_test_spec\":{\"swarming_dimensions\":{\"os\":\"Ubuntu-16.04\"},\"swarming_task_attrs\":{\"expiration\":13800,\"hard_timeout\":4200,\"priority\":35},\"tests\":[[\"d8testing_random_gc\",1,null,\"\",[],{},{}],[\"numfuzz\",2,null,\"marking\",[\"--total-timeout-sec=2100\",\"--stress-marking=1\"],{},{}],[\"numfuzz\",1,null,\"delay\",[\"--total-timeout-sec=2100\",\"--stress-delay-tasks=1\"],{},{}],[\"numfuzz\",1,null,\"threads\",[\"--total-timeout-sec=2100\",\"--stress-thread-pool-size=1\"],{},{}],[\"numfuzz\",3,null,\"combined\",[\"--total-timeout-sec=2100\",\"--stress-delay-tasks=4\",\"--stress-deopt=2\",\"--stress-compaction=2\",\"--stress-gc=4\",\"--stress-marking=4\",\"--stress-scavenge=4\",\"--stress-thread-pool-size=2\"],{},{}],[\"numfuzz\",1,null,\"scavenge\",[\"--total-timeout-sec=2100\",\"--stress-scavenge=1\"],{},{}],[\"numfuzz\",2,null,\"deopt\",[\"--total-timeout-sec=2100\",\"--stress-deopt=1\"],{},{}]]},\"revision\":\"7f0912269d17b509133389b5bc0580508bf1de6f\",\"swarm_hashes\":{\"d8_default\":\"9574dbb07f3343b2315418c87d74a5275bc0175cdeb8816fdf4cda56e279cfd7/319\",\"run-num-fuzzer\":\"7be438357e10b27322423434f080935b0965c74e57f763db018242d6c4d8831b/319\"}},\"tags\":[\"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/7f0912269d17b509133389b5bc0580508bf1de6f\",\"gitiles_ref:refs/heads/master\",\"parent_buildername:V8 Clusterfuzz Linux64 - debug builder\",\"parent_buildnumber:21131\",\"user_agent:recipe\"]},\"id\":\"9c05fefc-e706-46e0-af43-c20bbf7a576e\",\"title\":\"V8 Clusterfuzz Linux64 - debug builder/21131\"}]},\"newest_gitiles_revision\":\"7f0912269d17b509133389b5bc0580508bf1de6f\",\"oldest_gitiles_revision\":\"7f0912269d17b509133389b5bc0580508bf1de6f\",\"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\"},\"parent_buildername\":\"V8 Clusterfuzz Linux64 - debug builder\",\"parent_got_revision\":\"7f0912269d17b509133389b5bc0580508bf1de6f\",\"parent_got_revision_cp\":\"refs/heads/master@{#73558}\",\"parent_got_swarming_client_revision\":\"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\",\"parent_test_spec\":{\"swarming_dimensions\":{\"os\":\"Ubuntu-16.04\"},\"swarming_task_attrs\":{\"expiration\":13800,\"hard_timeout\":4200,\"priority\":35},\"tests\":[[\"d8testing_random_gc\",1,null,\"\",[],{},{}],[\"numfuzz\",2,null,\"marking\",[\"--total-timeout-sec=2100\",\"--stress-marking=1\"],{},{}],[\"numfuzz\",1,null,\"delay\",[\"--total-timeout-sec=2100\",\"--stress-delay-tasks=1\"],{},{}],[\"numfuzz\",1,null,\"threads\",[\"--total-timeout-sec=2100\",\"--stress-thread-pool-size=1\"],{},{}],[\"numfuzz\",3,null,\"combined\",[\"--total-timeout-sec=2100\",\"--stress-delay-tasks=4\",\"--stress-deopt=2\",\"--stress-compaction=2\",\"--stress-gc=4\",\"--stress-marking=4\",\"--stress-scavenge=4\",\"--stress-thread-pool-size=2\"],{},{}],[\"numfuzz\",1,null,\"scavenge\",[\"--total-timeout-sec=2100\",\"--stress-scavenge=1\"],{},{}],[\"numfuzz\",2,null,\"deopt\",[\"--total-timeout-sec=2100\",\"--stress-deopt=1\"],{},{}]]},\"revision\":\"7f0912269d17b509133389b5bc0580508bf1de6f\",\"swarm_hashes\":{\"d8_default\":\"9574dbb07f3343b2315418c87d74a5275bc0175cdeb8816fdf4cda56e279cfd7/319\",\"run-num-fuzzer\":\"7be438357e10b27322423434f080935b0965c74e57f763db018242d6c4d8831b/319\"}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:V8 NumFuzz - debug",
"scheduler_invocation_id:9017502220101442128",
"scheduler_job_id:v8/V8 NumFuzz - debug",
"user_agent:luci-scheduler",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/7f0912269d17b509133389b5bc0580508bf1de6f",
"gitiles_ref:refs/heads/master",
"parent_buildername:V8 Clusterfuzz Linux64 - debug builder",
"parent_buildnumber:21131",
"user_agent:recipe"
]
}
[08:52:39.121] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci",
"canary_preference": "PROD",
"created_by": "project:v8",
"created_ts": "1616403158057835",
"id": "8852072158095552736",
"parameters_json": "{\"builder_name\": \"V8 NumFuzz - debug\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"newest_gitiles_revision\": \"7f0912269d17b509133389b5bc0580508bf1de6f\", \"oldest_gitiles_revision\": \"7f0912269d17b509133389b5bc0580508bf1de6f\", \"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\"}, \"parent_buildername\": \"V8 Clusterfuzz Linux64 - debug builder\", \"parent_got_revision\": \"7f0912269d17b509133389b5bc0580508bf1de6f\", \"parent_got_revision_cp\": \"refs/heads/master@{#73558}\", \"parent_got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"parent_test_spec\": {\"swarming_dimensions\": {\"os\": \"Ubuntu-16.04\"}, \"swarming_task_attrs\": {\"expiration\": 13800.0, \"hard_timeout\": 4200.0, \"priority\": 35.0}, \"tests\": [[\"d8testing_random_gc\", 1.0, null, \"\", [], {}, {}], [\"numfuzz\", 2.0, null, \"marking\", [\"--total-timeout-sec=2100\", \"--stress-marking=1\"], {}, {}], [\"numfuzz\", 1.0, null, \"delay\", [\"--total-timeout-sec=2100\", \"--stress-delay-tasks=1\"], {}, {}], [\"numfuzz\", 1.0, null, \"threads\", [\"--total-timeout-sec=2100\", \"--stress-thread-pool-size=1\"], {}, {}], [\"numfuzz\", 3.0, null, \"combined\", [\"--total-timeout-sec=2100\", \"--stress-delay-tasks=4\", \"--stress-deopt=2\", \"--stress-compaction=2\", \"--stress-gc=4\", \"--stress-marking=4\", \"--stress-scavenge=4\", \"--stress-thread-pool-size=2\"], {}, {}], [\"numfuzz\", 1.0, null, \"scavenge\", [\"--total-timeout-sec=2100\", \"--stress-scavenge=1\"], {}, {}], [\"numfuzz\", 2.0, null, \"deopt\", [\"--total-timeout-sec=2100\", \"--stress-deopt=1\"], {}, {}]]}, \"revision\": \"7f0912269d17b509133389b5bc0580508bf1de6f\", \"swarm_hashes\": {\"d8_default\": \"9574dbb07f3343b2315418c87d74a5275bc0175cdeb8816fdf4cda56e279cfd7/319\", \"run-num-fuzzer\": \"7be438357e10b27322423434f080935b0965c74e57f763db018242d6c4d8831b/319\"}}, \"tags\": [\"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/7f0912269d17b509133389b5bc0580508bf1de6f\", \"gitiles_ref:refs/heads/master\", \"parent_buildername:V8 Clusterfuzz Linux64 - debug builder\", \"parent_buildnumber:21131\", \"user_agent:recipe\"]}, \"id\": \"9c05fefc-e706-46e0-af43-c20bbf7a576e\", \"title\": \"V8 Clusterfuzz Linux64 - debug builder/21131\"}]}, \"newest_gitiles_revision\": \"7f0912269d17b509133389b5bc0580508bf1de6f\", \"oldest_gitiles_revision\": \"7f0912269d17b509133389b5bc0580508bf1de6f\", \"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\"}, \"parent_buildername\": \"V8 Clusterfuzz Linux64 - debug builder\", \"parent_got_revision\": \"7f0912269d17b509133389b5bc0580508bf1de6f\", \"parent_got_revision_cp\": \"refs/heads/master@{#73558}\", \"parent_got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"parent_test_spec\": {\"swarming_dimensions\": {\"os\": \"Ubuntu-16.04\"}, \"swarming_task_attrs\": {\"expiration\": 13800.0, \"hard_timeout\": 4200.0, \"priority\": 35.0}, \"tests\": [[\"d8testing_random_gc\", 1.0, null, \"\", [], {}, {}], [\"numfuzz\", 2.0, null, \"marking\", [\"--total-timeout-sec=2100\", \"--stress-marking=1\"], {}, {}], [\"numfuzz\", 1.0, null, \"delay\", [\"--total-timeout-sec=2100\", \"--stress-delay-tasks=1\"], {}, {}], [\"numfuzz\", 1.0, null, \"threads\", [\"--total-timeout-sec=2100\", \"--stress-thread-pool-size=1\"], {}, {}], [\"numfuzz\", 3.0, null, \"combined\", [\"--total-timeout-sec=2100\", \"--stress-delay-tasks=4\", \"--stress-deopt=2\", \"--stress-compaction=2\", \"--stress-gc=4\", \"--stress-marking=4\", \"--stress-scavenge=4\", \"--stress-thread-pool-size=2\"], {}, {}], [\"numfuzz\", 1.0, null, \"scavenge\", [\"--total-timeout-sec=2100\", \"--stress-scavenge=1\"], {}, {}], [\"numfuzz\", 2.0, null, \"deopt\", [\"--total-timeout-sec=2100\", \"--stress-deopt=1\"], {}, {}]]}, \"revision\": \"7f0912269d17b509133389b5bc0580508bf1de6f\", \"swarm_hashes\": {\"d8_default\": \"9574dbb07f3343b2315418c87d74a5275bc0175cdeb8816fdf4cda56e279cfd7/319\", \"run-num-fuzzer\": \"7be438357e10b27322423434f080935b0965c74e57f763db018242d6c4d8831b/319\"}}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616403158945483",
"tags": [
"build_address:luci.v8.ci/V8 NumFuzz - debug/13583",
"builder:V8 NumFuzz - debug",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/7f0912269d17b509133389b5bc0580508bf1de6f",
"gitiles_ref:refs/heads/master",
"parent_buildername:V8 Clusterfuzz Linux64 - debug builder",
"parent_buildnumber:21131",
"scheduler_invocation_id:9017502220101442128",
"scheduler_job_id:v8/V8 NumFuzz - debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8852072158095552736/+/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": "1616403158945619",
"url": "https://ci.chromium.org/b/8852072158095552736",
"utcnow_ts": "1616403159113630"
}
}
[08:52:39.121] Task URL: https://ci.chromium.org/b/8852072158095552736
[08:52:39.121] Scheduling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:2:0) after 1m0s
[08:52:45.469] Received PubSub notification, asking Buildbucket for the build status
[08:52:45.919] Build 8852072158095552736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:39.138] Handling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:2:0)
[08:53:39.138] Timer tick, asking Buildbucket for the build status
[08:53:39.298] Build 8852072158095552736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:39.298] Scheduling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:4:0) after 1m0s
[08:54:39.346] Handling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:4:0)
[08:54:39.346] Timer tick, asking Buildbucket for the build status
[08:54:39.477] Build 8852072158095552736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:39.477] Scheduling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:5:0) after 1m0s
[08:55:39.651] Handling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:5:0)
[08:55:39.676] Build status: STARTED
[08:55:39.676] Scheduling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:6:0) after 2m23s
[08:58:02.931] Handling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:6:0)
[08:58:02.970] Build status: STARTED
[08:58:02.970] Scheduling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:7:0) after 5m39s
[09:03:42.108] Handling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:7:0)
[09:03:42.133] Build status: STARTED
[09:03:42.133] Scheduling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:8:0) after 2m58s
[09:06:40.205] Handling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:8:0)
[09:06:40.205] Timer tick, asking Buildbucket for the build status
[09:06:40.614] Build 8852072158095552736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:40.614] Scheduling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:9:0) after 1m0s
[09:07:40.848] Handling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:9:0)
[09:07:40.871] Build status: STARTED
[09:07:40.871] Scheduling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:10:0) after 4m50s
[09:12:30.889] Handling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:10:0)
[09:12:30.889] Timer tick, asking Buildbucket for the build status
[09:12:31.733] Build 8852072158095552736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:31.733] Scheduling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:11:0) after 1m0s
[09:13:31.762] Handling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:11:0)
[09:13:31.762] Timer tick, asking Buildbucket for the build status
[09:13:31.869] Build 8852072158095552736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:31.869] Scheduling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:12:0) after 1m0s
[09:14:31.900] Handling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:12:0)
[09:14:31.922] Build status: STARTED
[09:14:31.922] Scheduling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:13:0) after 9m28s
[09:23:59.948] Handling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:13:0)
[09:23:59.948] Timer tick, asking Buildbucket for the build status
[09:24:00.594] Build 8852072158095552736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:00.594] Scheduling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:14:0) after 1m0s
[09:25:00.864] Handling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:14:0)
[09:25:00.890] Build status: STARTED
[09:25:00.890] Scheduling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:15:0) after 6m56s
[09:31:57.415] Handling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:15:0)
[09:31:57.442] Build status: STARTED
[09:31:57.442] Scheduling timer "check-buildbucket-build-status" (v8/V8 NumFuzz - debug:9017502220101442128:16:0) after 6m10s
[09:35:29.285] Received PubSub notification, asking Buildbucket for the build status
[09:35:29.318] Build:
{
"id": "8852072158095552736",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 NumFuzz - debug"
},
"number": 13583,
"createdBy": "project:v8",
"createTime": "2021-03-22T08:52:38.057835Z",
"startTime": "2021-03-22T08:52:43.200284Z",
"endTime": "2021-03-22T09:35:28.896438961Z",
"updateTime": "2021-03-22T09:35:28.896921019Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "7f0912269d17b509133389b5bc0580508bf1de6f",
"ref": "refs/heads/master"
}
}
}
[09:35:29.318] Invocation finished in 42m52.558066528s with status SUCCEEDED