[09:19:22.263] New invocation is queued and will start shortly
[09:19:23.358] Starting the invocation (attempt 1)
[09:19:23.413] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:19:23.414] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:19:23.414] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9085267067493456000",
"parameters_json": "{\"builder_name\":\"Site Isolation Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"39cbb4ae7a3b3e2e5fc451bbaac64abd5ab56c1a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Site Isolation Android",
"scheduler_invocation_id:9085267067493456000",
"scheduler_job_id:chromium/Site Isolation Android",
"user_agent:luci-scheduler",
"buildset:commit/git/39cbb4ae7a3b3e2e5fc451bbaac64abd5ab56c1a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39cbb4ae7a3b3e2e5fc451bbaac64abd5ab56c1a",
"gitiles_ref:refs/heads/master"
]
}
[09:19:25.784] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551777563604461",
"id": "8919837005424954368",
"lease_expiration_ts": "1551921565552914",
"lease_key": "398375312",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"39cbb4ae7a3b3e2e5fc451bbaac64abd5ab56c1a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551777565650527",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/31171",
"builder:Site Isolation Android",
"buildset:commit/git/39cbb4ae7a3b3e2e5fc451bbaac64abd5ab56c1a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39cbb4ae7a3b3e2e5fc451bbaac64abd5ab56c1a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085267067493456000",
"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/8919837005424954368/+/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:4365f810b6457110",
"user_agent:luci-scheduler"
],
"updated_ts": "1551777565650622",
"url": "https://ci.chromium.org/b/8919837005424954368",
"utcnow_ts": "1551777565776752"
}
}
[09:19:25.784] Task URL: https://ci.chromium.org/b/8919837005424954368
[09:19:25.784] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:2:0) after 1m0s
[09:19:29.098] Received PubSub notification, asking Buildbucket for the build status
[09:19:29.193] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:25.800] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:2:0)
[09:20:25.800] Timer tick, asking Buildbucket for the build status
[09:20:25.856] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:25.856] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:4:0) after 1m0s
[09:21:25.827] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:4:0)
[09:21:25.827] Timer tick, asking Buildbucket for the build status
[09:21:25.904] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:25.904] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:5:0) after 1m0s
[09:22:25.922] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:5:0)
[09:22:25.922] Timer tick, asking Buildbucket for the build status
[09:22:25.995] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:25.995] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:6:0) after 1m0s
[09:23:26.001] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:6:0)
[09:23:26.001] Timer tick, asking Buildbucket for the build status
[09:23:26.112] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:26.112] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:7:0) after 1m0s
[09:24:26.119] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:7:0)
[09:24:26.119] Timer tick, asking Buildbucket for the build status
[09:24:26.267] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:26.267] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:8:0) after 1m0s
[09:25:26.288] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:8:0)
[09:25:26.288] Timer tick, asking Buildbucket for the build status
[09:25:26.386] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:26.386] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:9:0) after 1m0s
[09:26:26.397] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:9:0)
[09:26:26.397] Timer tick, asking Buildbucket for the build status
[09:26:26.455] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:26.455] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:10:0) after 1m0s
[09:27:26.473] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:10:0)
[09:27:26.473] Timer tick, asking Buildbucket for the build status
[09:27:26.660] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:26.660] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:11:0) after 1m0s
[09:28:26.675] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:11:0)
[09:28:26.675] Timer tick, asking Buildbucket for the build status
[09:28:26.735] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:26.735] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:12:0) after 1m0s
[09:29:26.753] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:12:0)
[09:29:26.753] Timer tick, asking Buildbucket for the build status
[09:29:27.410] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:27.410] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:13:0) after 1m0s
[09:30:27.413] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:13:0)
[09:30:27.413] Timer tick, asking Buildbucket for the build status
[09:30:27.477] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:27.477] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:14:0) after 1m0s
[09:31:27.481] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:14:0)
[09:31:27.481] Timer tick, asking Buildbucket for the build status
[09:31:27.588] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:27.588] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:15:0) after 1m0s
[09:32:27.600] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:15:0)
[09:32:27.600] Timer tick, asking Buildbucket for the build status
[09:32:27.709] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:27.709] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:16:0) after 1m0s
[09:33:27.727] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:16:0)
[09:33:27.727] Timer tick, asking Buildbucket for the build status
[09:33:27.852] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:27.852] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:17:0) after 1m0s
[09:34:27.936] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:17:0)
[09:34:27.936] Timer tick, asking Buildbucket for the build status
[09:34:28.119] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:28.119] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:18:0) after 1m0s
[09:35:28.131] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:18:0)
[09:35:28.131] Timer tick, asking Buildbucket for the build status
[09:35:28.185] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:28.185] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:19:0) after 1m0s
[09:36:28.192] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:19:0)
[09:36:28.192] Timer tick, asking Buildbucket for the build status
[09:36:28.272] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:28.272] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:20:0) after 1m0s
[09:37:28.246] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:20:0)
[09:37:28.246] Timer tick, asking Buildbucket for the build status
[09:37:28.306] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:28.306] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:21:0) after 1m0s
[09:38:28.279] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:21:0)
[09:38:28.279] Timer tick, asking Buildbucket for the build status
[09:38:28.336] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:28.336] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:22:0) after 1m0s
[09:39:28.355] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:22:0)
[09:39:28.355] Timer tick, asking Buildbucket for the build status
[09:39:28.567] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:28.568] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:23:0) after 1m0s
[09:40:28.591] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:23:0)
[09:40:28.591] Timer tick, asking Buildbucket for the build status
[09:40:28.723] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:28.723] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:24:0) after 1m0s
[09:41:28.742] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:24:0)
[09:41:28.742] Timer tick, asking Buildbucket for the build status
[09:41:28.826] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:28.826] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:25:0) after 1m0s
[09:42:28.828] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:25:0)
[09:42:28.828] Timer tick, asking Buildbucket for the build status
[09:42:28.930] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:28.930] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:26:0) after 1m0s
[09:43:28.940] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:26:0)
[09:43:28.940] Timer tick, asking Buildbucket for the build status
[09:43:28.986] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:28.986] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:27:0) after 1m0s
[09:44:29.016] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:27:0)
[09:44:29.016] Timer tick, asking Buildbucket for the build status
[09:44:29.172] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:29.172] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:28:0) after 1m0s
[09:45:29.179] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:28:0)
[09:45:29.179] Timer tick, asking Buildbucket for the build status
[09:45:29.295] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:29.295] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:29:0) after 1m0s
[09:46:29.280] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:29:0)
[09:46:29.280] Timer tick, asking Buildbucket for the build status
[09:46:29.371] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:29.371] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:30:0) after 1m0s
[09:47:29.393] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:30:0)
[09:47:29.393] Timer tick, asking Buildbucket for the build status
[09:47:29.493] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:29.493] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:31:0) after 1m0s
[09:48:29.528] Handling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:31:0)
[09:48:29.528] Timer tick, asking Buildbucket for the build status
[09:48:29.632] Build 8919837005424954368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:29.632] Scheduling timer "check-buildbucket-build-status" (chromium/Site Isolation Android:9085267067493456000:32:0) after 1m0s
[09:49:07.336] Received PubSub notification, asking Buildbucket for the build status
[09:49:07.431] Build 8919837005424954368: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:49:07.431] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1551779346477479",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551777563604461",
"id": "8919837005424954368",
"parameters_json": "{\"builder_name\": \"Site Isolation Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"39cbb4ae7a3b3e2e5fc451bbaac64abd5ab56c1a\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Site Isolation Android\"], \"kvm\": [\"0\"], \"id\": [\"swarm2384-c4\"], \"server_version\": [\"4143-92e279d\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-f\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Ivy_Bridge_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e961d9d7e9bca679490a4aaa8599bd975f9b7e0b713a988ed6895a059d04d2ac_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_swarming_client_revision\": \"7a61cf37d6a0163f0ec02d495289a1d038e62457\", \"got_revision\": \"39cbb4ae7a3b3e2e5fc451bbaac64abd5ab56c1a\", \"recipe\": \"chromium\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"got_dawn_revision\": \"b47470daa7377a804df5d94e7a93b5925a57f84a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26947}\", \"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"got_revision_cp\": \"refs/heads/master@{#637636}\", \"branch\": \"refs/heads/master\", \"revision\": \"39cbb4ae7a3b3e2e5fc451bbaac64abd5ab56c1a\", \"swarm_hashes_refs/heads/master(at){#637636}_without_patch\": {\"components_unittests\": \"27eed20564b793fc07ca60cbfabecd897a58d4b1\", \"components_browsertests\": \"e9bb2757d4a09d22ecfc278d9f75bf277e247d8c\", \"content_browsertests\": \"a1ed6d0205eead6699c51cd9c96f692ecd66fb7d\", \"unit_tests\": \"cc7f350a969010e1c4e6d92d821a17e16993eee3\", \"content_unittests\": \"7523e017f6f5c214b6aa8c5665d263aeaa0d1e44\"}, \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Site Isolation Android\", \"got_webrtc_revision\": \"b678940d3a444c9bf5b53a0bc2e2951f222dab6a\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"b8cff9e9347cee1fe46092a10ae039df2fdd4c33\", \"got_v8_revision\": \"d8b0d88de4b7d73ea02abb8511c146944d6ccf67\", \"got_v8_revision_cp\": \"refs/heads/7.4.271@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 31171, \"path_config\": \"generic\", \"swarm_hashes\": {\"components_unittests\": \"27eed20564b793fc07ca60cbfabecd897a58d4b1\", \"components_browsertests\": \"e9bb2757d4a09d22ecfc278d9f75bf277e247d8c\", \"content_browsertests\": \"a1ed6d0205eead6699c51cd9c96f692ecd66fb7d\", \"unit_tests\": \"cc7f350a969010e1c4e6d92d821a17e16993eee3\", \"content_unittests\": \"7523e017f6f5c214b6aa8c5665d263aeaa0d1e44\"}, \"bot_id\": \"swarm2384-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551777568499084",
"status": "COMPLETED",
"status_changed_ts": "1551779347036636",
"tags": [
"build_address:luci.chromium.ci/Site Isolation Android/31171",
"builder:Site Isolation Android",
"buildset:commit/git/39cbb4ae7a3b3e2e5fc451bbaac64abd5ab56c1a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39cbb4ae7a3b3e2e5fc451bbaac64abd5ab56c1a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085267067493456000",
"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/8919837005424954368/+/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:4365f810b6457110",
"user_agent:luci-scheduler"
],
"updated_ts": "1551779347038001",
"url": "https://ci.chromium.org/b/8919837005424954368",
"utcnow_ts": "1551779347421700"
}
}
[09:49:07.431] Invocation finished in 29m45.183561236s with status SUCCEEDED