[00:34:14.651] New invocation is queued and will start shortly
[00:34:17.546] Starting the invocation (attempt 1)
[00:34:17.586] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:34:17.586] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:34:17.587] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9056309076371757248",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"201c5e601d8d77502882ae0b6736426dd784b0e0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9056309076371757248",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/201c5e601d8d77502882ae0b6736426dd784b0e0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/201c5e601d8d77502882ae0b6736426dd784b0e0",
"gitiles_ref:refs/heads/master"
]
}
[00:34:18.594] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1579394057891117",
"id": "8890879012310697984",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"201c5e601d8d77502882ae0b6736426dd784b0e0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579394058294347",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/40938",
"builder:Site Isolation Android",
"buildset:commit/git/201c5e601d8d77502882ae0b6736426dd784b0e0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/201c5e601d8d77502882ae0b6736426dd784b0e0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056309076371757248",
"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/8890879012310697984/+/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": "1579394058294797",
"url": "https://ci.chromium.org/b/8890879012310697984",
"utcnow_ts": "1579394058586532"
}
}
[00:34:18.594] Task URL: https://ci.chromium.org/b/8890879012310697984
[00:34:18.594] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:2:0) after 1m0s
[00:35:20.085] Received PubSub notification, asking Buildbucket for the build status
[00:35:21.244] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:25.250] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:2:0)
[00:35:25.250] Timer tick, asking Buildbucket for the build status
[00:35:25.644] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:25.644] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:4:0) after 1m0s
[00:36:26.061] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:4:0)
[00:36:26.061] Timer tick, asking Buildbucket for the build status
[00:36:26.443] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:26.443] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:5:0) after 1m0s
[00:37:26.636] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:5:0)
[00:37:26.636] Timer tick, asking Buildbucket for the build status
[00:37:26.923] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:26.923] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:6:0) after 1m0s
[00:38:27.266] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:6:0)
[00:38:27.266] Timer tick, asking Buildbucket for the build status
[00:38:27.427] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:27.427] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:7:0) after 1m0s
[00:39:27.771] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:7:0)
[00:39:27.771] Timer tick, asking Buildbucket for the build status
[00:39:27.994] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:27.994] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:8:0) after 1m0s
[00:40:28.171] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:8:0)
[00:40:28.171] Timer tick, asking Buildbucket for the build status
[00:40:28.569] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:28.569] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:9:0) after 1m0s
[00:41:28.783] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:9:0)
[00:41:28.783] Timer tick, asking Buildbucket for the build status
[00:41:28.971] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:28.971] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:10:0) after 1m0s
[00:42:29.138] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:10:0)
[00:42:29.138] Timer tick, asking Buildbucket for the build status
[00:42:29.338] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:29.338] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:11:0) after 1m0s
[00:43:29.739] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:11:0)
[00:43:29.739] Timer tick, asking Buildbucket for the build status
[00:43:29.999] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:29.999] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:12:0) after 1m0s
[00:44:30.166] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:12:0)
[00:44:30.166] Timer tick, asking Buildbucket for the build status
[00:44:30.524] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:30.524] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:13:0) after 1m0s
[00:45:30.594] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:13:0)
[00:45:30.594] Timer tick, asking Buildbucket for the build status
[00:45:30.733] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:30.733] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:14:0) after 1m0s
[00:46:30.781] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:14:0)
[00:46:30.781] Timer tick, asking Buildbucket for the build status
[00:46:30.917] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:30.917] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:15:0) after 1m0s
[00:47:31.135] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:15:0)
[00:47:31.135] Timer tick, asking Buildbucket for the build status
[00:47:31.298] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:31.298] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:16:0) after 1m0s
[00:48:31.511] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:16:0)
[00:48:31.511] Timer tick, asking Buildbucket for the build status
[00:48:31.658] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:31.658] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:17:0) after 1m0s
[00:49:31.682] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:17:0)
[00:49:31.682] Timer tick, asking Buildbucket for the build status
[00:49:31.870] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:31.870] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:18:0) after 1m0s
[00:50:31.871] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:18:0)
[00:50:31.871] Timer tick, asking Buildbucket for the build status
[00:50:33.653] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:33.653] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:19:0) after 1m0s
[00:51:33.708] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:19:0)
[00:51:33.708] Timer tick, asking Buildbucket for the build status
[00:51:33.959] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:33.959] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:20:0) after 1m0s
[00:52:33.979] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:20:0)
[00:52:33.979] Timer tick, asking Buildbucket for the build status
[00:52:34.231] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:34.231] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:21:0) after 1m0s
[00:53:34.344] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:21:0)
[00:53:34.344] Timer tick, asking Buildbucket for the build status
[00:53:34.619] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:34.619] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:22:0) after 1m0s
[00:54:34.641] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:22:0)
[00:54:34.641] Timer tick, asking Buildbucket for the build status
[00:54:37.069] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:37.069] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:23:0) after 1m0s
[00:55:37.078] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:23:0)
[00:55:37.078] Timer tick, asking Buildbucket for the build status
[00:55:37.328] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:37.328] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:24:0) after 1m0s
[00:56:37.353] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:24:0)
[00:56:37.353] Timer tick, asking Buildbucket for the build status
[00:56:37.520] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:37.520] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:25:0) after 1m0s
[00:57:37.694] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:25:0)
[00:57:37.694] Timer tick, asking Buildbucket for the build status
[00:57:38.172] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:38.172] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:26:0) after 1m0s
[00:58:38.272] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:26:0)
[00:58:38.273] Timer tick, asking Buildbucket for the build status
[00:58:38.444] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:38.444] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:27:0) after 1m0s
[00:59:38.591] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:27:0)
[00:59:38.591] Timer tick, asking Buildbucket for the build status
[00:59:40.222] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:40.222] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:28:0) after 1m0s
[01:00:40.407] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:28:0)
[01:00:40.407] Timer tick, asking Buildbucket for the build status
[01:00:42.214] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:42.214] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:29:0) after 1m0s
[01:01:42.239] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:29:0)
[01:01:42.239] Timer tick, asking Buildbucket for the build status
[01:01:42.587] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:42.587] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:30:0) after 1m0s
[01:02:42.767] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:30:0)
[01:02:42.767] Timer tick, asking Buildbucket for the build status
[01:02:43.032] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:43.032] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:31:0) after 1m0s
[01:03:43.106] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:31:0)
[01:03:43.106] Timer tick, asking Buildbucket for the build status
[01:03:43.715] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:43.716] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:32:0) after 1m0s
[01:04:43.799] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:32:0)
[01:04:43.799] Timer tick, asking Buildbucket for the build status
[01:04:43.923] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:43.924] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:33:0) after 1m0s
[01:05:44.065] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:33:0)
[01:05:44.065] Timer tick, asking Buildbucket for the build status
[01:05:44.212] Build 8890879012310697984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:44.212] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9056309076371757248:34:0) after 1m0s
[01:05:58.424] Received PubSub notification, asking Buildbucket for the build status
[01:05:58.537] Build 8890879012310697984: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:05:58.537] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1579395957857553",
"created_by": "project:chromium",
"created_ts": "1579394057891117",
"failure_reason": "BUILD_FAILURE",
"id": "8890879012310697984",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"201c5e601d8d77502882ae0b6736426dd784b0e0\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 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\\n\\n**site_per_process_content_unittests on Android device Nexus 5X** failed because of:\\n\\n- ChildProcessSecurityPolicyTest.HandleExtendsSecurityStateLifetime\"}, \"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-central1-b-8-58-e78a\", \"branch\": \"refs/heads/master\", \"buildername\": \"Site Isolation Android\", \"buildnumber\": 40938, \"got_angle_revision\": \"579c59400f860d9f63510a5da9441bb7ccca422a\", \"got_dawn_revision\": \"d8f8c29bb25a5a1658da37aae05b9b4cd4353640\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"201c5e601d8d77502882ae0b6736426dd784b0e0\", \"got_revision_cp\": \"refs/heads/master@{#733179}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"11cb891a01a2b47cc2135ef19ca18046662476e0\", \"got_v8_revision\": \"3bb6c2dd702c09576d665319c09a89242710a496\", \"got_v8_revision_cp\": \"refs/heads/8.1.222@{#1}\", \"got_webrtc_revision\": \"c380e97ee62a3e1a75290bfb0e438ca267c6237c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30312}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"201c5e601d8d77502882ae0b6736426dd784b0e0\", \"swarm_hashes\": {\"components_browsertests\": \"68443cebee809cdfbe2b9481f247dd3edd65db1f\", \"components_unittests\": \"74d0018f9240ab60deb4358753761f33a192c763\", \"content_browsertests\": \"e1feaae99b531b63b4661f824b4e8139b021bc2a\", \"content_unittests\": \"66dd0b534a0c2c725b1359f3bad1c77a0e47c0b9\", \"unit_tests\": \"20c2a667829bc70102a9699009b7a255323cf64b\"}, \"swarm_hashes_refs/heads/master(at){#733179}_without_patch\": {\"components_browsertests\": \"68443cebee809cdfbe2b9481f247dd3edd65db1f\", \"components_unittests\": \"74d0018f9240ab60deb4358753761f33a192c763\", \"content_browsertests\": \"e1feaae99b531b63b4661f824b4e8139b021bc2a\", \"content_unittests\": \"66dd0b534a0c2c725b1359f3bad1c77a0e47c0b9\", \"unit_tests\": \"20c2a667829bc70102a9699009b7a255323cf64b\"}}, \"ui\": {\"info\": \"2 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\\n\\n**site_per_process_content_unittests on Android device Nexus 5X** failed because of:\\n\\n- ChildProcessSecurityPolicyTest.HandleExtendsSecurityStateLifetime\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1579394101656211",
"status": "COMPLETED",
"status_changed_ts": "1579394058294347",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/40938",
"builder:Site Isolation Android",
"buildset:commit/git/201c5e601d8d77502882ae0b6736426dd784b0e0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/201c5e601d8d77502882ae0b6736426dd784b0e0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056309076371757248",
"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/8890879012310697984/+/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:49d40a51bb4db810",
"user_agent:luci-scheduler"
],
"updated_ts": "1579395957932868",
"url": "https://ci.chromium.org/b/8890879012310697984",
"utcnow_ts": "1579395958526629"
}
}
[01:05:58.537] Invocation finished in 31m43.901966019s with status FAILED