[10:20:55.303] New invocation is queued and will start shortly
[10:20:56.798] Starting the invocation (attempt 1)
[10:20:56.840] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:20:56.840] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:20:56.840] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9081729913387315888",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"b6d95d9de65b865dabad6902865b064f09c45711\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9081729913387315888",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/b6d95d9de65b865dabad6902865b064f09c45711",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b6d95d9de65b865dabad6902865b064f09c45711",
"gitiles_ref:refs/heads/master"
]
}
[10:20:59.076] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1555150857480072",
"id": "8916299850425340592",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"b6d95d9de65b865dabad6902865b064f09c45711\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555150858485240",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/32905",
"builder:Site Isolation Android",
"buildset:commit/git/b6d95d9de65b865dabad6902865b064f09c45711",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b6d95d9de65b865dabad6902865b064f09c45711",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081729913387315888",
"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/8916299850425340592/+/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": "1555150858485375",
"url": "https://ci.chromium.org/b/8916299850425340592",
"utcnow_ts": "1555150859055692"
}
}
[10:20:59.076] Task URL: https://ci.chromium.org/b/8916299850425340592
[10:20:59.076] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:2:0) after 1m0s
[10:21:10.773] Received PubSub notification, asking Buildbucket for the build status
[10:21:11.395] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:59.088] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:2:0)
[10:21:59.088] Timer tick, asking Buildbucket for the build status
[10:21:59.587] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:59.587] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:4:0) after 1m0s
[10:22:59.594] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:4:0)
[10:22:59.594] Timer tick, asking Buildbucket for the build status
[10:23:00.112] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:00.112] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:5:0) after 1m0s
[10:24:00.152] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:5:0)
[10:24:00.152] Timer tick, asking Buildbucket for the build status
[10:24:00.507] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:00.507] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:6:0) after 1m0s
[10:25:00.514] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:6:0)
[10:25:00.514] Timer tick, asking Buildbucket for the build status
[10:25:00.630] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:00.630] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:7:0) after 1m0s
[10:26:00.646] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:7:0)
[10:26:00.646] Timer tick, asking Buildbucket for the build status
[10:26:00.806] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:00.806] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:8:0) after 1m0s
[10:27:00.823] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:8:0)
[10:27:00.823] Timer tick, asking Buildbucket for the build status
[10:27:00.958] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:00.958] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:9:0) after 1m0s
[10:28:00.976] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:9:0)
[10:28:00.976] Timer tick, asking Buildbucket for the build status
[10:28:02.185] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:02.185] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:10:0) after 1m0s
[10:29:02.204] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:10:0)
[10:29:02.204] Timer tick, asking Buildbucket for the build status
[10:29:02.363] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:02.363] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:11:0) after 1m0s
[10:30:02.384] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:11:0)
[10:30:02.384] Timer tick, asking Buildbucket for the build status
[10:30:04.304] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:04.304] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:12:0) after 1m0s
[10:31:04.319] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:12:0)
[10:31:04.319] Timer tick, asking Buildbucket for the build status
[10:31:04.722] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:04.722] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:13:0) after 1m0s
[10:32:04.750] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:13:0)
[10:32:04.750] Timer tick, asking Buildbucket for the build status
[10:32:05.022] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:05.022] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:14:0) after 1m0s
[10:33:05.042] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:14:0)
[10:33:05.042] Timer tick, asking Buildbucket for the build status
[10:33:05.863] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:05.863] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:15:0) after 1m0s
[10:34:05.881] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:15:0)
[10:34:05.881] Timer tick, asking Buildbucket for the build status
[10:34:05.995] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:05.995] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:16:0) after 1m0s
[10:35:06.013] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:16:0)
[10:35:06.013] Timer tick, asking Buildbucket for the build status
[10:35:06.699] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:06.699] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:17:0) after 1m0s
[10:36:06.717] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:17:0)
[10:36:06.717] Timer tick, asking Buildbucket for the build status
[10:36:06.908] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:06.908] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:18:0) after 1m0s
[10:37:06.960] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:18:0)
[10:37:06.960] Timer tick, asking Buildbucket for the build status
[10:37:07.175] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:07.175] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:19:0) after 1m0s
[10:38:07.195] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:19:0)
[10:38:07.195] Timer tick, asking Buildbucket for the build status
[10:38:07.532] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:07.532] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:20:0) after 1m0s
[10:39:07.547] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:20:0)
[10:39:07.547] Timer tick, asking Buildbucket for the build status
[10:39:07.737] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:07.737] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:21:0) after 1m0s
[10:40:07.761] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:21:0)
[10:40:07.761] Timer tick, asking Buildbucket for the build status
[10:40:08.989] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:08.989] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:22:0) after 1m0s
[10:41:09.010] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:22:0)
[10:41:09.010] Timer tick, asking Buildbucket for the build status
[10:41:09.191] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:09.191] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:23:0) after 1m0s
[10:42:09.231] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:23:0)
[10:42:09.231] Timer tick, asking Buildbucket for the build status
[10:42:09.699] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:09.699] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:24:0) after 1m0s
[10:43:09.717] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:24:0)
[10:43:09.717] Timer tick, asking Buildbucket for the build status
[10:43:09.817] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:09.817] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:25:0) after 1m0s
[10:44:09.850] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:25:0)
[10:44:09.850] Timer tick, asking Buildbucket for the build status
[10:44:10.031] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:10.031] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:26:0) after 1m0s
[10:45:10.110] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:26:0)
[10:45:10.110] Timer tick, asking Buildbucket for the build status
[10:45:11.072] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:11.072] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:27:0) after 1m0s
[10:46:11.089] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:27:0)
[10:46:11.089] Timer tick, asking Buildbucket for the build status
[10:46:11.235] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:11.235] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:28:0) after 1m0s
[10:47:11.252] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:28:0)
[10:47:11.252] Timer tick, asking Buildbucket for the build status
[10:47:11.713] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:11.713] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:29:0) after 1m0s
[10:48:11.689] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:29:0)
[10:48:11.689] Timer tick, asking Buildbucket for the build status
[10:48:12.179] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:12.179] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:30:0) after 1m0s
[10:49:12.197] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:30:0)
[10:49:12.197] Timer tick, asking Buildbucket for the build status
[10:49:12.361] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:12.361] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:31:0) after 1m0s
[10:50:12.377] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:31:0)
[10:50:12.377] Timer tick, asking Buildbucket for the build status
[10:50:12.788] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:12.788] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:32:0) after 1m0s
[10:51:12.811] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:32:0)
[10:51:12.811] Timer tick, asking Buildbucket for the build status
[10:51:13.863] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:13.863] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:33:0) after 1m0s
[10:52:13.898] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:33:0)
[10:52:13.898] Timer tick, asking Buildbucket for the build status
[10:52:14.051] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:14.051] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:34:0) after 1m0s
[10:53:14.072] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:34:0)
[10:53:14.072] Timer tick, asking Buildbucket for the build status
[10:53:14.274] Build 8916299850425340592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:14.274] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9081729913387315888:35:0) after 1m0s
[10:53:19.434] Received PubSub notification, asking Buildbucket for the build status
[10:53:19.531] Build 8916299850425340592: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:53:19.531] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1555152798464314",
"created_by": "project:chromium",
"created_ts": "1555150857480072",
"id": "8916299850425340592",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"b6d95d9de65b865dabad6902865b064f09c45711\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"swarm2384-c4\", \"branch\": \"refs/heads/master\", \"buildername\": \"Site Isolation Android\", \"buildnumber\": 32905, \"got_angle_revision\": \"065f8dc35f9dffead39ced85a0ab1ba447ccf843\", \"got_dawn_revision\": \"bff933affcffd6d907c5356237668ba755d4e266\", \"got_nacl_revision\": \"6abc006f6760ec49350cd45e8bccbff4809725ac\", \"got_revision\": \"b6d95d9de65b865dabad6902865b064f09c45711\", \"got_revision_cp\": \"refs/heads/master@{#650595}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"81260d20b1cfb15752913acb657113c92c6c0026\", \"got_v8_revision_cp\": \"refs/heads/7.5.269@{#1}\", \"got_webrtc_revision\": \"3af5c4c3547b7da427b0cc87b0cdc0d56de37bcd\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27602}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"b6d95d9de65b865dabad6902865b064f09c45711\", \"swarm_hashes\": {\"components_browsertests\": \"fae9349b30da433ae74b196192f801a9b52e14eb\", \"components_unittests\": \"daa1e603a8b7d4a758f5d8977db22daa9b383b4c\", \"content_browsertests\": \"c25f2718369db441b226048cd22aeaeff2bec7ff\", \"content_unittests\": \"5402779f4d6ef93aa61896421d5ae18ca990d4ee\", \"unit_tests\": \"35b938a0ec358bd77f3df5af01c5bd9d66a290c3\"}, \"swarm_hashes_refs/heads/master(at){#650595}_without_patch\": {\"components_browsertests\": \"fae9349b30da433ae74b196192f801a9b52e14eb\", \"components_unittests\": \"daa1e603a8b7d4a758f5d8977db22daa9b383b4c\", \"content_browsertests\": \"c25f2718369db441b226048cd22aeaeff2bec7ff\", \"content_unittests\": \"5402779f4d6ef93aa61896421d5ae18ca990d4ee\", \"unit_tests\": \"35b938a0ec358bd77f3df5af01c5bd9d66a290c3\"}}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Site Isolation Android\"], \"caches\": [\"builder_e961d9d7e9bca679490a4aaa8599bd975f9b7e0b713a988ed6895a059d04d2ac_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Ivy_Bridge_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"swarm2384-c4\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"inside_docker\": [\"0\"], \"kvm\": [\"0\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4253-3d44409\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1555150870222311",
"status": "COMPLETED",
"status_changed_ts": "1555152799220520",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/32905",
"builder:Site Isolation Android",
"buildset:commit/git/b6d95d9de65b865dabad6902865b064f09c45711",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b6d95d9de65b865dabad6902865b064f09c45711",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081729913387315888",
"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/8916299850425340592/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:442f08737714a910",
"user_agent:luci-scheduler"
],
"updated_ts": "1555152799223099",
"url": "https://ci.chromium.org/b/8916299850425340592",
"utcnow_ts": "1555152799522762"
}
}
[10:53:19.531] Invocation finished in 32m24.258814679s with status SUCCEEDED