[07:09:21.015] New invocation is queued and will start shortly
[07:09:22.248] Starting the invocation (attempt 1)
[07:09:22.283] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:09:22.284] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:09:22.284] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054019294291659728",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ef8dd847517eab983d74a5c746516fdea3b438c3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9054019294291659728",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/ef8dd847517eab983d74a5c746516fdea3b438c3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef8dd847517eab983d74a5c746516fdea3b438c3",
"gitiles_ref:refs/heads/master"
]
}
[07:09:22.913] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581577762424415",
"id": "8888589232147029072",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ef8dd847517eab983d74a5c746516fdea3b438c3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581577762808950",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/41679",
"builder:Site Isolation Android",
"buildset:commit/git/ef8dd847517eab983d74a5c746516fdea3b438c3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef8dd847517eab983d74a5c746516fdea3b438c3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054019294291659728",
"scheduler_job_id:chromium/Site Isolation Android",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8888589232147029072/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1581577762809060",
"url": "https://ci.chromium.org/b/8888589232147029072",
"utcnow_ts": "1581577762906930"
}
}
[07:09:22.913] Task URL: https://ci.chromium.org/b/8888589232147029072
[07:09:22.913] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:2:0) after 1m0s
[07:10:11.491] Received PubSub notification, asking Buildbucket for the build status
[07:10:12.550] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:22.934] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:2:0)
[07:10:22.934] Timer tick, asking Buildbucket for the build status
[07:10:23.036] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:23.036] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:4:0) after 1m0s
[07:11:23.062] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:4:0)
[07:11:23.062] Timer tick, asking Buildbucket for the build status
[07:11:23.362] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:23.362] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:5:0) after 1m0s
[07:12:23.380] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:5:0)
[07:12:23.380] Timer tick, asking Buildbucket for the build status
[07:12:23.511] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:23.511] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:6:0) after 1m0s
[07:13:23.527] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:6:0)
[07:13:23.527] Timer tick, asking Buildbucket for the build status
[07:13:23.711] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:23.711] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:7:0) after 1m0s
[07:14:23.714] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:7:0)
[07:14:23.714] Timer tick, asking Buildbucket for the build status
[07:14:23.873] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:23.873] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:8:0) after 1m0s
[07:15:23.961] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:8:0)
[07:15:23.961] Timer tick, asking Buildbucket for the build status
[07:15:24.158] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:24.158] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:9:0) after 1m0s
[07:16:24.202] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:9:0)
[07:16:24.202] Timer tick, asking Buildbucket for the build status
[07:16:24.515] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:24.515] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:10:0) after 1m0s
[07:17:24.533] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:10:0)
[07:17:24.533] Timer tick, asking Buildbucket for the build status
[07:17:24.792] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:24.792] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:11:0) after 1m0s
[07:18:24.822] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:11:0)
[07:18:24.822] Timer tick, asking Buildbucket for the build status
[07:18:25.045] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:25.045] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:12:0) after 1m0s
[07:19:25.075] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:12:0)
[07:19:25.075] Timer tick, asking Buildbucket for the build status
[07:19:25.261] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:25.261] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:13:0) after 1m0s
[07:20:25.279] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:13:0)
[07:20:25.279] Timer tick, asking Buildbucket for the build status
[07:20:25.666] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:25.666] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:14:0) after 1m0s
[07:21:25.705] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:14:0)
[07:21:25.705] Timer tick, asking Buildbucket for the build status
[07:21:25.908] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:25.908] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:15:0) after 1m0s
[07:22:25.926] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:15:0)
[07:22:25.926] Timer tick, asking Buildbucket for the build status
[07:22:26.082] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:26.082] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:16:0) after 1m0s
[07:23:26.060] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:16:0)
[07:23:26.060] Timer tick, asking Buildbucket for the build status
[07:23:26.283] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:26.283] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:17:0) after 1m0s
[07:24:26.307] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:17:0)
[07:24:26.307] Timer tick, asking Buildbucket for the build status
[07:24:26.477] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:26.477] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:18:0) after 1m0s
[07:25:26.510] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:18:0)
[07:25:26.511] Timer tick, asking Buildbucket for the build status
[07:25:26.602] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:26.602] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:19:0) after 1m0s
[07:26:26.589] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:19:0)
[07:26:26.589] Timer tick, asking Buildbucket for the build status
[07:26:26.740] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:26.740] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:20:0) after 1m0s
[07:27:26.813] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:20:0)
[07:27:26.813] Timer tick, asking Buildbucket for the build status
[07:27:27.654] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:27.654] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:21:0) after 1m0s
[07:28:27.696] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:21:0)
[07:28:27.696] Timer tick, asking Buildbucket for the build status
[07:28:28.196] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:28.196] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:22:0) after 1m0s
[07:29:28.220] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:22:0)
[07:29:28.220] Timer tick, asking Buildbucket for the build status
[07:29:29.043] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:29.043] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:23:0) after 1m0s
[07:30:29.097] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:23:0)
[07:30:29.097] Timer tick, asking Buildbucket for the build status
[07:30:29.270] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:29.270] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:24:0) after 1m0s
[07:31:29.295] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:24:0)
[07:31:29.295] Timer tick, asking Buildbucket for the build status
[07:31:29.791] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:29.791] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:25:0) after 1m0s
[07:32:29.797] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:25:0)
[07:32:29.797] Timer tick, asking Buildbucket for the build status
[07:32:29.937] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:29.937] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:26:0) after 1m0s
[07:33:30.114] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:26:0)
[07:33:30.114] Timer tick, asking Buildbucket for the build status
[07:33:31.744] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:31.744] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:27:0) after 1m0s
[07:34:32.036] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:27:0)
[07:34:32.036] Timer tick, asking Buildbucket for the build status
[07:34:32.554] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:32.554] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:28:0) after 1m0s
[07:35:32.731] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:28:0)
[07:35:32.731] Timer tick, asking Buildbucket for the build status
[07:35:33.111] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:33.111] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:29:0) after 1m0s
[07:36:33.154] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:29:0)
[07:36:33.154] Timer tick, asking Buildbucket for the build status
[07:36:33.691] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:33.691] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:30:0) after 1m0s
[07:37:33.891] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:30:0)
[07:37:33.891] Timer tick, asking Buildbucket for the build status
[07:37:34.034] Build 8888589232147029072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:34.034] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054019294291659728:31:0) after 1m0s
[07:37:45.415] Received PubSub notification, asking Buildbucket for the build status
[07:37:45.638] Build 8888589232147029072: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[07:37:45.638] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581579464947796",
"created_by": "project:chromium",
"created_ts": "1581577762424415",
"failure_reason": "BUILD_FAILURE",
"id": "8888589232147029072",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ef8dd847517eab983d74a5c746516fdea3b438c3\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**site_per_process_content_browsertests on Android device Nexus 5X** failed because of:\\n\\n- All/BlockedSchemeNavigationBrowserTest.PDF_NavigationFromFrame_TopFrameHasBlockedScheme_Block/1\\n\\n- All/BlockedSchemeNavigationBrowserTest.PDF_NavigationFromFrame_Block/1\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-94-1m7i\", \"branch\": \"refs/heads/master\", \"buildername\": \"Site Isolation Android\", \"buildnumber\": 41679, \"got_angle_revision\": \"74ab0bffb526308f9732c99c7efc59fd718ee89c\", \"got_dawn_revision\": \"2fd618192985122aafb494ac5e9a39f572ef46fc\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"ef8dd847517eab983d74a5c746516fdea3b438c3\", \"got_revision_cp\": \"refs/heads/master@{#740973}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"ac4e1d236088f90e3bc97aa67002ffe56042e4f2\", \"got_v8_revision\": \"acf9c0def690e10a2dc454d0c0aab1ff73ec7cee\", \"got_v8_revision_cp\": \"refs/heads/8.2.67@{#1}\", \"got_webrtc_revision\": \"d4c3c3a454215b21f02d2a772a729b4c87d6b33e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30486}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ef8dd847517eab983d74a5c746516fdea3b438c3\", \"swarm_hashes\": {\"components_browsertests\": \"24f5f5eb9820e7739a3f283ced28f09e8818fd9d\", \"components_unittests\": \"6992c315231466703a631f3a867d27db4b061b19\", \"content_browsertests\": \"aad1aaefd3f044ecaf1ac75e0499b9f475a16a54\", \"content_unittests\": \"4fb65e80cf476d581141a26563c055d68503ae3c\", \"unit_tests\": \"183a03a400df007300902e5535042b55e3db0baa\"}, \"swarm_hashes_refs/heads/master(at){#740973}_without_patch\": {\"components_browsertests\": \"24f5f5eb9820e7739a3f283ced28f09e8818fd9d\", \"components_unittests\": \"6992c315231466703a631f3a867d27db4b061b19\", \"content_browsertests\": \"aad1aaefd3f044ecaf1ac75e0499b9f475a16a54\", \"content_unittests\": \"4fb65e80cf476d581141a26563c055d68503ae3c\", \"unit_tests\": \"183a03a400df007300902e5535042b55e3db0baa\"}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**site_per_process_content_browsertests on Android device Nexus 5X** failed because of:\\n\\n- All/BlockedSchemeNavigationBrowserTest.PDF_NavigationFromFrame_TopFrameHasBlockedScheme_Block/1\\n\\n- All/BlockedSchemeNavigationBrowserTest.PDF_NavigationFromFrame_Block/1\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581577810901876",
"status": "COMPLETED",
"status_changed_ts": "1581577762808950",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/41679",
"builder:Site Isolation Android",
"buildset:commit/git/ef8dd847517eab983d74a5c746516fdea3b438c3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef8dd847517eab983d74a5c746516fdea3b438c3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054019294291659728",
"scheduler_job_id:chromium/Site Isolation Android",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8888589232147029072/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4a563300b1a7a710",
"user_agent:luci-scheduler"
],
"updated_ts": "1581579465153584",
"url": "https://ci.chromium.org/b/8888589232147029072",
"utcnow_ts": "1581579465631079"
}
}
[07:37:45.638] Invocation finished in 28m24.642777807s with status FAILED