[04:20:17.677] New invocation is queued and will start shortly
[04:20:19.810] Starting the invocation (attempt 1)
[04:20:19.862] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:20:19.862] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:20:19.862] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9081933796015659248",
"parameters_json": "{\"builder_name\":\"Linux Asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src\",\"revision\":\"71ec39e355a8a7e57534d543f62abefbb8bf2f0d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Asan",
"scheduler_invocation_id:9081933796015659248",
"scheduler_job_id:webrtc/Linux Asan",
"user_agent:luci-scheduler",
"buildset:commit/git/71ec39e355a8a7e57534d543f62abefbb8bf2f0d",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/71ec39e355a8a7e57534d543f62abefbb8bf2f0d",
"gitiles_ref:refs/heads/master"
]
}
[04:20:20.685] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"created_by": "project:webrtc",
"created_ts": "1554956420067432",
"id": "8916503732829925040",
"parameters_json": "{\"builder_name\": \"Linux Asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"71ec39e355a8a7e57534d543f62abefbb8bf2f0d\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554956420525506",
"tags": [
"build_address:luci.webrtc.ci/Linux Asan/20167",
"builder:Linux Asan",
"buildset:commit/git/71ec39e355a8a7e57534d543f62abefbb8bf2f0d",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/71ec39e355a8a7e57534d543f62abefbb8bf2f0d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081933796015659248",
"scheduler_job_id:webrtc/Linux Asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8916503732829925040/+/annotations",
"swarming_tag:luci_project:webrtc",
"swarming_tag:recipe_name:webrtc/standalone",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1554956420525635",
"url": "https://ci.chromium.org/b/8916503732829925040",
"utcnow_ts": "1554956420675096"
}
}
[04:20:20.685] Task URL: https://ci.chromium.org/b/8916503732829925040
[04:20:20.685] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:2:0) after 1m0s
[04:21:20.753] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:2:0)
[04:21:20.753] Timer tick, asking Buildbucket for the build status
[04:21:20.993] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:21:20.993] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:3:0) after 1m0s
[04:22:21.067] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:3:0)
[04:22:21.067] Timer tick, asking Buildbucket for the build status
[04:22:21.256] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:22:21.256] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:4:0) after 1m0s
[04:23:21.278] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:4:0)
[04:23:21.278] Timer tick, asking Buildbucket for the build status
[04:23:21.783] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:23:21.783] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:5:0) after 1m0s
[04:24:21.774] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:5:0)
[04:24:21.774] Timer tick, asking Buildbucket for the build status
[04:24:21.827] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:24:21.827] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:6:0) after 1m0s
[04:25:21.844] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:6:0)
[04:25:21.844] Timer tick, asking Buildbucket for the build status
[04:25:21.960] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:25:21.960] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:7:0) after 1m0s
[04:26:21.941] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:7:0)
[04:26:21.941] Timer tick, asking Buildbucket for the build status
[04:26:22.011] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:26:22.011] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:8:0) after 1m0s
[04:27:22.015] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:8:0)
[04:27:22.015] Timer tick, asking Buildbucket for the build status
[04:27:22.106] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:27:22.106] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:9:0) after 1m0s
[04:28:22.091] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:9:0)
[04:28:22.091] Timer tick, asking Buildbucket for the build status
[04:28:22.186] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:28:22.186] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:10:0) after 1m0s
[04:29:22.192] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:10:0)
[04:29:22.192] Timer tick, asking Buildbucket for the build status
[04:29:22.405] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:29:22.405] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:11:0) after 1m0s
[04:30:22.399] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:11:0)
[04:30:22.399] Timer tick, asking Buildbucket for the build status
[04:30:22.623] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:30:22.623] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:12:0) after 1m0s
[04:31:22.611] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:12:0)
[04:31:22.611] Timer tick, asking Buildbucket for the build status
[04:31:22.796] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:31:22.796] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:13:0) after 1m0s
[04:32:22.790] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:13:0)
[04:32:22.790] Timer tick, asking Buildbucket for the build status
[04:32:22.941] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:32:22.941] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:14:0) after 1m0s
[04:33:23.050] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:14:0)
[04:33:23.050] Timer tick, asking Buildbucket for the build status
[04:33:23.157] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:33:23.157] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:15:0) after 1m0s
[04:34:23.176] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:15:0)
[04:34:23.176] Timer tick, asking Buildbucket for the build status
[04:34:23.384] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:34:23.384] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:16:0) after 1m0s
[04:35:23.403] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:16:0)
[04:35:23.403] Timer tick, asking Buildbucket for the build status
[04:35:23.632] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:35:23.632] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:17:0) after 1m0s
[04:36:23.650] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:17:0)
[04:36:23.650] Timer tick, asking Buildbucket for the build status
[04:36:24.211] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:36:24.211] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:18:0) after 1m0s
[04:37:24.234] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:18:0)
[04:37:24.234] Timer tick, asking Buildbucket for the build status
[04:37:24.502] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:37:24.502] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:19:0) after 1m0s
[04:38:24.496] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:19:0)
[04:38:24.496] Timer tick, asking Buildbucket for the build status
[04:38:24.906] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:38:24.906] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:20:0) after 1m0s
[04:39:24.902] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:20:0)
[04:39:24.902] Timer tick, asking Buildbucket for the build status
[04:39:24.985] Build 8916503732829925040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:39:24.985] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:21:0) after 1m0s
[04:39:44.635] Received PubSub notification, asking Buildbucket for the build status
[04:39:44.706] Build 8916503732829925040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:24.982] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:21:0)
[04:40:24.982] Timer tick, asking Buildbucket for the build status
[04:40:25.260] Build 8916503732829925040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:25.260] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:23:0) after 1m0s
[04:41:25.238] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:23:0)
[04:41:25.238] Timer tick, asking Buildbucket for the build status
[04:41:25.331] Build 8916503732829925040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:25.331] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:24:0) after 1m0s
[04:42:25.338] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:24:0)
[04:42:25.338] Timer tick, asking Buildbucket for the build status
[04:42:25.404] Build 8916503732829925040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:25.404] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:25:0) after 1m0s
[04:43:25.422] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:25:0)
[04:43:25.422] Timer tick, asking Buildbucket for the build status
[04:43:25.564] Build 8916503732829925040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:25.564] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:26:0) after 1m0s
[04:44:25.591] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:26:0)
[04:44:25.591] Timer tick, asking Buildbucket for the build status
[04:44:25.871] Build 8916503732829925040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:25.871] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:27:0) after 1m0s
[04:45:25.944] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:27:0)
[04:45:25.944] Timer tick, asking Buildbucket for the build status
[04:45:26.016] Build 8916503732829925040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:26.016] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:28:0) after 1m0s
[04:46:26.035] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:28:0)
[04:46:26.035] Timer tick, asking Buildbucket for the build status
[04:46:26.125] Build 8916503732829925040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:26.125] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:29:0) after 1m0s
[04:47:26.162] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:29:0)
[04:47:26.162] Timer tick, asking Buildbucket for the build status
[04:47:26.314] Build 8916503732829925040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:26.314] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:30:0) after 1m0s
[04:48:26.330] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:30:0)
[04:48:26.330] Timer tick, asking Buildbucket for the build status
[04:48:26.417] Build 8916503732829925040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:26.417] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:31:0) after 1m0s
[04:49:26.433] Handling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:31:0)
[04:49:26.433] Timer tick, asking Buildbucket for the build status
[04:49:26.586] Build 8916503732829925040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:26.586] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux Asan:9081933796015659248:32:0) after 1m0s
[04:50:17.915] Received PubSub notification, asking Buildbucket for the build status
[04:50:17.961] Build 8916503732829925040: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:50:17.961] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"completed_ts": "1554958217008451",
"created_by": "project:webrtc",
"created_ts": "1554956420067432",
"id": "8916503732829925040",
"parameters_json": "{\"builder_name\": \"Linux Asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"71ec39e355a8a7e57534d543f62abefbb8bf2f0d\"}}",
"project": "webrtc",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$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-webrtc-ci-trusty-2-j60q\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Asan\", \"buildnumber\": 20167, \"got_clang_revision\": \"357692-1\", \"got_revision\": \"71ec39e355a8a7e57534d543f62abefbb8bf2f0d\", \"got_revision_cp\": \"refs/heads/master@{#27553}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.webrtc\", \"path_config\": \"generic\", \"recipe\": \"webrtc/standalone\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"71ec39e355a8a7e57534d543f62abefbb8bf2f0d\", \"swarm_hashes\": {\"audio_decoder_unittests\": \"ceed31f3f2e3149602cd96aa8a67e968e4f62a00\", \"common_audio_unittests\": \"6ccaaf49148ddca3c5065ba80b159524fa89f38d\", \"common_video_unittests\": \"41e27a5c7e1ba28b07b20610d1103cf173dd511a\", \"low_bandwidth_audio_test\": \"c4af9d1e7ee974f8ffc3e65f046587f76f5d835b\", \"modules_tests\": \"42e8fc8f9fb829add3b9856a034187ed94e40300\", \"modules_unittests\": \"5f5385b53895e2882a1392a17c5488520d67bb0d\", \"peerconnection_unittests\": \"1f7d2d99c0fe56e1c2b3b063d27053364774dbbe\", \"rtc_media_unittests\": \"70ae288bd68d29c6082960389fafb66697794d25\", \"rtc_pc_unittests\": \"094da4732ac0c851402132e144b5d54b44d4e085\", \"rtc_stats_unittests\": \"b2f0f132eb91349d7f6e9d3fb34d271065cc8fe2\", \"rtc_unittests\": \"dba5e2958c8b94d97e4518f16f2fa53e7a6f97c7\", \"slow_tests\": \"35eadb7ba80bf47e843b99157dad2207a586436a\", \"system_wrappers_unittests\": \"a2fb1c64e57ec55650c5eeea8c3dd5656d5d184d\", \"test_support_unittests\": \"c8c834b281c5d7013fe40f1cdcfd1b8e60202253\", \"tools_unittests\": \"59fd9081a03cc4fd0f9c69c5fd3157eee2ca4419\", \"video_engine_tests\": \"6548ca917ddc07a0c12c0c5cd7f4b28f235d6ff0\", \"webrtc_nonparallel_tests\": \"205cebcd7945171f84d27ffb57e213c09dabb59b\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_659dd105ea03987792d4053fbbba393abe7f346036c314442882afecee9cf953_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-webrtc-ci-trusty-2-j60q\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.webrtc.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4243-f38882f\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1554957583879985",
"status": "COMPLETED",
"status_changed_ts": "1554958217535030",
"tags": [
"build_address:luci.webrtc.ci/Linux Asan/20167",
"builder:Linux Asan",
"buildset:commit/git/71ec39e355a8a7e57534d543f62abefbb8bf2f0d",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/71ec39e355a8a7e57534d543f62abefbb8bf2f0d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081933796015659248",
"scheduler_job_id:webrtc/Linux Asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8916503732829925040/+/annotations",
"swarming_tag:luci_project:webrtc",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:webrtc/standalone",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4423718ec5628310",
"user_agent:luci-scheduler"
],
"updated_ts": "1554958217537260",
"url": "https://ci.chromium.org/b/8916503732829925040",
"utcnow_ts": "1554958217949839"
}
}
[04:50:17.961] Invocation finished in 30m0.315704884s with status SUCCEEDED