[21:25:32.275] New invocation is queued and will start shortly
[21:25:33.468] Starting the invocation (attempt 1)
[21:25:33.501] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:25:33.501] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:25:33.501] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054961994247867424",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5f20d5817192bac0fcaa945d814bac41cb939d1e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9054961994247867424",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/5f20d5817192bac0fcaa945d814bac41cb939d1e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5f20d5817192bac0fcaa945d814bac41cb939d1e",
"gitiles_ref:refs/heads/master"
]
}
[21:25:34.740] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580678733633117",
"id": "8889531932159794704",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5f20d5817192bac0fcaa945d814bac41cb939d1e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580678734436801",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/41386",
"builder:Site Isolation Android",
"buildset:commit/git/5f20d5817192bac0fcaa945d814bac41cb939d1e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5f20d5817192bac0fcaa945d814bac41cb939d1e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054961994247867424",
"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/8889531932159794704/+/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": "1580678734436941",
"url": "https://ci.chromium.org/b/8889531932159794704",
"utcnow_ts": "1580678734680368"
}
}
[21:25:34.740] Task URL: https://ci.chromium.org/b/8889531932159794704
[21:25:34.740] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:2:0) after 1m0s
[21:26:06.833] Received PubSub notification, asking Buildbucket for the build status
[21:26:07.082] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:39.086] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:2:0)
[21:26:39.086] Timer tick, asking Buildbucket for the build status
[21:26:39.263] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:39.263] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:4:0) after 1m0s
[21:27:39.309] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:4:0)
[21:27:39.309] Timer tick, asking Buildbucket for the build status
[21:27:39.445] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:39.445] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:5:0) after 1m0s
[21:28:39.594] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:5:0)
[21:28:39.594] Timer tick, asking Buildbucket for the build status
[21:28:39.786] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:39.786] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:6:0) after 1m0s
[21:29:39.804] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:6:0)
[21:29:39.804] Timer tick, asking Buildbucket for the build status
[21:29:40.483] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:40.483] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:7:0) after 1m0s
[21:30:40.526] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:7:0)
[21:30:40.526] Timer tick, asking Buildbucket for the build status
[21:30:40.829] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:40.829] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:8:0) after 1m0s
[21:31:40.902] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:8:0)
[21:31:40.902] Timer tick, asking Buildbucket for the build status
[21:31:41.308] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:41.308] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:9:0) after 1m0s
[21:32:41.336] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:9:0)
[21:32:41.336] Timer tick, asking Buildbucket for the build status
[21:32:42.246] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:42.246] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:10:0) after 1m0s
[21:33:42.316] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:10:0)
[21:33:42.316] Timer tick, asking Buildbucket for the build status
[21:33:42.495] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:42.495] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:11:0) after 1m0s
[21:34:42.558] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:11:0)
[21:34:42.558] Timer tick, asking Buildbucket for the build status
[21:34:42.768] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:42.768] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:12:0) after 1m0s
[21:35:42.839] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:12:0)
[21:35:42.839] Timer tick, asking Buildbucket for the build status
[21:35:43.266] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:43.266] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:13:0) after 1m0s
[21:36:43.302] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:13:0)
[21:36:43.302] Timer tick, asking Buildbucket for the build status
[21:36:43.846] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:43.846] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:14:0) after 1m0s
[21:37:43.881] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:14:0)
[21:37:43.881] Timer tick, asking Buildbucket for the build status
[21:37:44.055] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:44.055] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:15:0) after 1m0s
[21:38:44.130] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:15:0)
[21:38:44.130] Timer tick, asking Buildbucket for the build status
[21:38:44.273] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:44.273] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:16:0) after 1m0s
[21:39:44.293] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:16:0)
[21:39:44.293] Timer tick, asking Buildbucket for the build status
[21:39:44.631] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:44.631] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:17:0) after 1m0s
[21:40:44.640] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:17:0)
[21:40:44.640] Timer tick, asking Buildbucket for the build status
[21:40:45.285] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:45.285] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:18:0) after 1m0s
[21:41:45.300] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:18:0)
[21:41:45.300] Timer tick, asking Buildbucket for the build status
[21:41:45.452] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:45.452] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:19:0) after 1m0s
[21:42:45.468] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:19:0)
[21:42:45.468] Timer tick, asking Buildbucket for the build status
[21:42:45.959] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:45.959] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:20:0) after 1m0s
[21:43:45.976] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:20:0)
[21:43:45.976] Timer tick, asking Buildbucket for the build status
[21:43:46.139] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:46.139] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:21:0) after 1m0s
[21:44:46.158] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:21:0)
[21:44:46.158] Timer tick, asking Buildbucket for the build status
[21:44:46.347] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:46.347] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:22:0) after 1m0s
[21:45:46.362] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:22:0)
[21:45:46.362] Timer tick, asking Buildbucket for the build status
[21:45:46.563] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:46.564] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:23:0) after 1m0s
[21:46:46.763] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:23:0)
[21:46:46.763] Timer tick, asking Buildbucket for the build status
[21:46:47.491] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:47.491] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:24:0) after 1m0s
[21:47:47.879] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:24:0)
[21:47:47.879] Timer tick, asking Buildbucket for the build status
[21:47:48.309] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:48.309] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:25:0) after 1m0s
[21:48:48.554] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:25:0)
[21:48:48.554] Timer tick, asking Buildbucket for the build status
[21:48:48.879] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:48.879] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:26:0) after 1m0s
[21:49:48.975] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:26:0)
[21:49:48.975] Timer tick, asking Buildbucket for the build status
[21:49:49.149] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:49.149] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:27:0) after 1m0s
[21:50:49.241] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:27:0)
[21:50:49.241] Timer tick, asking Buildbucket for the build status
[21:50:49.803] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:49.803] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:28:0) after 1m0s
[21:51:49.925] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:28:0)
[21:51:49.925] Timer tick, asking Buildbucket for the build status
[21:51:50.126] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:50.126] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:29:0) after 1m0s
[21:52:50.614] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:29:0)
[21:52:50.614] Timer tick, asking Buildbucket for the build status
[21:52:50.784] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:50.784] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:30:0) after 1m0s
[21:53:50.937] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:30:0)
[21:53:50.937] Timer tick, asking Buildbucket for the build status
[21:53:51.085] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:51.085] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:31:0) after 1m0s
[21:54:51.119] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:31:0)
[21:54:51.119] Timer tick, asking Buildbucket for the build status
[21:54:51.666] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:51.666] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:32:0) after 1m0s
[21:55:51.916] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:32:0)
[21:55:51.916] Timer tick, asking Buildbucket for the build status
[21:55:52.043] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:52.043] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:33:0) after 1m0s
[21:56:52.065] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:33:0)
[21:56:52.065] Timer tick, asking Buildbucket for the build status
[21:56:52.373] Build 8889531932159794704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:52.373] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9054961994247867424:34:0) after 1m0s
[21:57:08.717] Received PubSub notification, asking Buildbucket for the build status
[21:57:08.824] Build 8889531932159794704: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:57:08.824] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1580680628064578",
"created_by": "project:chromium",
"created_ts": "1580678733633117",
"failure_reason": "BUILD_FAILURE",
"id": "8889531932159794704",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5f20d5817192bac0fcaa945d814bac41cb939d1e\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 Test Suite(s) failed.\\n\\n**site_per_process_components_unittests on Android device Nexus 5X** failed because of:\\n\\n- CollectUserDataActionTest.UserDataComplete_Payment\\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-27-pq82\", \"branch\": \"refs/heads/master\", \"buildername\": \"Site Isolation Android\", \"buildnumber\": 41386, \"got_angle_revision\": \"49a53d684affafc0bbaa2d4c2414113fe95329ce\", \"got_dawn_revision\": \"2dd9ed07760d58db3f7163529af28b07c811583e\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"5f20d5817192bac0fcaa945d814bac41cb939d1e\", \"got_revision_cp\": \"refs/heads/master@{#737644}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"41a7302e9c62c207e5ac5bcce40f16a1e418c42b\", \"got_v8_revision\": \"2b9bdb1d1f242f58351766d4b341e29c8cd1690c\", \"got_v8_revision_cp\": \"refs/heads/8.2.1@{#1}\", \"got_webrtc_revision\": \"9d56b0113fff7d833006d54c38701b321852ba5c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30440}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5f20d5817192bac0fcaa945d814bac41cb939d1e\", \"swarm_hashes\": {\"components_browsertests\": \"58978893cdbc20656608f95565634c845d237761\", \"components_unittests\": \"af2a126b6f07329dcc3a0f36a7c8713d5665510d\", \"content_browsertests\": \"9c39ac0e3afca2e53e080bee14ceeecbab6e65b1\", \"content_unittests\": \"c7269ca8fbf5752e402a7d31f80f0a6d25ce616e\", \"unit_tests\": \"354f5325ff3143f988484a9ca85a4a8c87a8b8bc\"}, \"swarm_hashes_refs/heads/master(at){#737644}_without_patch\": {\"components_browsertests\": \"58978893cdbc20656608f95565634c845d237761\", \"components_unittests\": \"af2a126b6f07329dcc3a0f36a7c8713d5665510d\", \"content_browsertests\": \"9c39ac0e3afca2e53e080bee14ceeecbab6e65b1\", \"content_unittests\": \"c7269ca8fbf5752e402a7d31f80f0a6d25ce616e\", \"unit_tests\": \"354f5325ff3143f988484a9ca85a4a8c87a8b8bc\"}}, \"ui\": {\"info\": \"2 Test Suite(s) failed.\\n\\n**site_per_process_components_unittests on Android device Nexus 5X** failed because of:\\n\\n- CollectUserDataActionTest.UserDataComplete_Payment\\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": "1580678759596491",
"status": "COMPLETED",
"status_changed_ts": "1580678734436801",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/41386",
"builder:Site Isolation Android",
"buildset:commit/git/5f20d5817192bac0fcaa945d814bac41cb939d1e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5f20d5817192bac0fcaa945d814bac41cb939d1e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054961994247867424",
"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/8889531932159794704/+/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:4a209ceb5c5b5910",
"user_agent:luci-scheduler"
],
"updated_ts": "1580680628349055",
"url": "https://ci.chromium.org/b/8889531932159794704",
"utcnow_ts": "1580680628817882"
}
}
[21:57:08.824] Invocation finished in 31m36.564695507s with status FAILED