[07:15:44.130] New invocation is queued and will start shortly
[07:15:45.201] Starting the invocation (attempt 1)
[07:15:45.238] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:15:45.238] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:15:45.238] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9078208282596003856",
"parameters_json": "{\"builder_name\":\"Linux MSan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src\",\"revision\":\"afb8d5cdaeadce0cd4f10f0c5f6f748c9d8d3cad\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux MSan",
"scheduler_invocation_id:9078208282596003856",
"scheduler_job_id:webrtc/Linux MSan",
"user_agent:luci-scheduler",
"buildset:commit/git/afb8d5cdaeadce0cd4f10f0c5f6f748c9d8d3cad",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/afb8d5cdaeadce0cd4f10f0c5f6f748c9d8d3cad",
"gitiles_ref:refs/heads/master"
]
}
[07:15:46.806] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"created_by": "project:webrtc",
"created_ts": "1558509345480254",
"id": "8912778220512167584",
"parameters_json": "{\"builder_name\": \"Linux MSan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"afb8d5cdaeadce0cd4f10f0c5f6f748c9d8d3cad\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558509346674390",
"tags": [
"build_address:luci.webrtc.ci/Linux MSan/18980",
"builder:Linux MSan",
"buildset:commit/git/afb8d5cdaeadce0cd4f10f0c5f6f748c9d8d3cad",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/afb8d5cdaeadce0cd4f10f0c5f6f748c9d8d3cad",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078208282596003856",
"scheduler_job_id:webrtc/Linux MSan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8912778220512167584/+/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": "1558509346676228",
"url": "https://ci.chromium.org/b/8912778220512167584",
"utcnow_ts": "1558509346799912"
}
}
[07:15:46.806] Task URL: https://ci.chromium.org/b/8912778220512167584
[07:15:46.806] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:2:0) after 1m0s
[07:16:46.822] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:2:0)
[07:16:46.822] Timer tick, asking Buildbucket for the build status
[07:16:47.499] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:16:47.499] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:3:0) after 1m0s
[07:17:47.554] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:3:0)
[07:17:47.554] Timer tick, asking Buildbucket for the build status
[07:17:47.691] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:17:47.691] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:4:0) after 1m0s
[07:18:47.705] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:4:0)
[07:18:47.705] Timer tick, asking Buildbucket for the build status
[07:18:47.772] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:18:47.772] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:5:0) after 1m0s
[07:19:47.794] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:5:0)
[07:19:47.794] Timer tick, asking Buildbucket for the build status
[07:19:47.888] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:19:47.888] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:6:0) after 1m0s
[07:20:47.909] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:6:0)
[07:20:47.909] Timer tick, asking Buildbucket for the build status
[07:20:48.045] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:20:48.046] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:7:0) after 1m0s
[07:21:48.073] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:7:0)
[07:21:48.073] Timer tick, asking Buildbucket for the build status
[07:21:49.110] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:21:49.110] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:8:0) after 1m0s
[07:22:49.193] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:8:0)
[07:22:49.193] Timer tick, asking Buildbucket for the build status
[07:22:49.282] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:22:49.282] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:9:0) after 1m0s
[07:23:49.304] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:9:0)
[07:23:49.304] Timer tick, asking Buildbucket for the build status
[07:23:49.903] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:23:49.903] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:10:0) after 1m0s
[07:24:50.038] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:10:0)
[07:24:50.038] Timer tick, asking Buildbucket for the build status
[07:24:50.362] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:24:50.362] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:11:0) after 1m0s
[07:25:50.380] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:11:0)
[07:25:50.380] Timer tick, asking Buildbucket for the build status
[07:25:50.589] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:25:50.589] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:12:0) after 1m0s
[07:26:50.601] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:12:0)
[07:26:50.601] Timer tick, asking Buildbucket for the build status
[07:26:51.229] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:26:51.229] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:13:0) after 1m0s
[07:27:51.240] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:13:0)
[07:27:51.240] Timer tick, asking Buildbucket for the build status
[07:27:51.358] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:27:51.358] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:14:0) after 1m0s
[07:28:51.328] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:14:0)
[07:28:51.328] Timer tick, asking Buildbucket for the build status
[07:28:51.819] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:28:51.820] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:15:0) after 1m0s
[07:29:51.966] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:15:0)
[07:29:51.966] Timer tick, asking Buildbucket for the build status
[07:29:52.063] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:29:52.063] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:16:0) after 1m0s
[07:30:52.088] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:16:0)
[07:30:52.088] Timer tick, asking Buildbucket for the build status
[07:30:53.279] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:30:53.279] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:17:0) after 1m0s
[07:31:53.268] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:17:0)
[07:31:53.268] Timer tick, asking Buildbucket for the build status
[07:31:53.620] Build 8912778220512167584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:31:53.620] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:18:0) after 1m0s
[07:32:37.338] Received PubSub notification, asking Buildbucket for the build status
[07:32:37.464] Build 8912778220512167584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:53.613] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:18:0)
[07:32:53.613] Timer tick, asking Buildbucket for the build status
[07:32:53.704] Build 8912778220512167584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:53.704] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:20:0) after 1m0s
[07:33:53.725] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:20:0)
[07:33:53.725] Timer tick, asking Buildbucket for the build status
[07:33:54.041] Build 8912778220512167584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:54.041] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:21:0) after 1m0s
[07:34:54.063] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:21:0)
[07:34:54.063] Timer tick, asking Buildbucket for the build status
[07:34:54.146] Build 8912778220512167584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:54.146] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:22:0) after 1m0s
[07:35:54.150] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:22:0)
[07:35:54.150] Timer tick, asking Buildbucket for the build status
[07:35:54.506] Build 8912778220512167584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:54.506] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:23:0) after 1m0s
[07:36:54.531] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:23:0)
[07:36:54.532] Timer tick, asking Buildbucket for the build status
[07:36:54.598] Build 8912778220512167584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:54.598] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:24:0) after 1m0s
[07:37:54.618] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:24:0)
[07:37:54.618] Timer tick, asking Buildbucket for the build status
[07:37:54.700] Build 8912778220512167584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:54.700] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:25:0) after 1m0s
[07:38:54.802] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:25:0)
[07:38:54.802] Timer tick, asking Buildbucket for the build status
[07:38:55.158] Build 8912778220512167584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:55.158] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:26:0) after 1m0s
[07:39:55.180] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:26:0)
[07:39:55.180] Timer tick, asking Buildbucket for the build status
[07:39:55.270] Build 8912778220512167584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:55.270] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:27:0) after 1m0s
[07:40:55.322] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:27:0)
[07:40:55.322] Timer tick, asking Buildbucket for the build status
[07:40:55.476] Build 8912778220512167584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:55.476] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:28:0) after 1m0s
[07:41:55.526] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:28:0)
[07:41:55.526] Timer tick, asking Buildbucket for the build status
[07:41:56.089] Build 8912778220512167584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:56.089] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:29:0) after 1m0s
[07:42:56.107] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:29:0)
[07:42:56.107] Timer tick, asking Buildbucket for the build status
[07:42:57.328] Build 8912778220512167584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:57.328] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:30:0) after 1m0s
[07:43:57.351] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:30:0)
[07:43:57.351] Timer tick, asking Buildbucket for the build status
[07:43:57.619] Build 8912778220512167584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:57.619] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:31:0) after 1m0s
[07:44:57.637] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:31:0)
[07:44:57.637] Timer tick, asking Buildbucket for the build status
[07:44:57.756] Build 8912778220512167584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:57.756] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9078208282596003856:32:0) after 1m0s
[07:45:17.329] Received PubSub notification, asking Buildbucket for the build status
[07:45:17.382] Build 8912778220512167584: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:45:17.382] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"completed_ts": "1558511116068733",
"created_by": "project:webrtc",
"created_ts": "1558509345480254",
"id": "8912778220512167584",
"parameters_json": "{\"builder_name\": \"Linux MSan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"afb8d5cdaeadce0cd4f10f0c5f6f748c9d8d3cad\"}}",
"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-xenial-3-o7q4\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux MSan\", \"buildnumber\": 18980, \"got_clang_revision\": \"361212-1\", \"got_revision\": \"afb8d5cdaeadce0cd4f10f0c5f6f748c9d8d3cad\", \"got_revision_cp\": \"refs/heads/master@{#28019}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.webrtc\", \"path_config\": \"generic\", \"recipe\": \"webrtc/standalone\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"afb8d5cdaeadce0cd4f10f0c5f6f748c9d8d3cad\", \"swarm_hashes\": {\"audio_decoder_unittests\": \"acfdb90651c5fdaa65b160636e85b38a6ca37b4e\", \"common_audio_unittests\": \"22f29fb41011fbe7524909bf9a063683cde4abc4\", \"common_video_unittests\": \"16fe29e9d17d296c4f117b355ef3f7640ebab7f8\", \"low_bandwidth_audio_test\": \"795d9b5c20621137d6b2b2c70ddac9fd4c274602\", \"modules_tests\": \"0bd72585e2ea18313dea5338e4700b91486cb525\", \"modules_unittests\": \"4ad7500189cad315c3d1fc77467d8c59da409e1f\", \"peerconnection_unittests\": \"389037d8f615197e1bfd3c59c330b40d8cc72fd6\", \"rtc_media_unittests\": \"58fe369cdb23dae2647d266bdc0b808cadf08c1f\", \"rtc_pc_unittests\": \"59baeea38422f8584a9e667099b43dc404bfbca6\", \"rtc_stats_unittests\": \"3def6ce7223e87ca9bd1795c05afb4bff51803a9\", \"rtc_unittests\": \"6e0ded6744cad5ca69f80328865c6d28af4bc3ef\", \"slow_tests\": \"88b70260da48eed9bf6faddc3fed60af81e12557\", \"system_wrappers_unittests\": \"6ab6b691a542a37924d7da0f95aaaa99329c36fc\", \"test_support_unittests\": \"31a504563266169f1be4e9626de03db67ba9e563\", \"tools_unittests\": \"47d3f91907e7ba125a623017b169e3084f6c55f8\", \"video_engine_tests\": \"b1a817d29d9e2c5bb27f13b3d5702ac21f8bc1eb\", \"webrtc_nonparallel_tests\": \"27eb9a7307d5bf4308ea613d9ded99c17f1401f5\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_3ede6a545b7f5f02d8e754bf3d80550bff536ffd8cd1f0e4f2eed09fd4ce3e22_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-xenial-3-o7q4\"], \"image\": [\"chrome-xenial-19041001-3b730ae8765\"], \"inside_docker\": [\"0\"], \"kvm\": [\"0\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.webrtc.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4303-bae210e\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1558510356073038",
"status": "COMPLETED",
"status_changed_ts": "1558511116647873",
"tags": [
"build_address:luci.webrtc.ci/Linux MSan/18980",
"builder:Linux MSan",
"buildset:commit/git/afb8d5cdaeadce0cd4f10f0c5f6f748c9d8d3cad",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/afb8d5cdaeadce0cd4f10f0c5f6f748c9d8d3cad",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078208282596003856",
"scheduler_job_id:webrtc/Linux MSan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8912778220512167584/+/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:44f736f4bf018f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558511116675515",
"url": "https://ci.chromium.org/b/8912778220512167584",
"utcnow_ts": "1558511117376100"
}
}
[07:45:17.382] Invocation finished in 29m33.273556832s with status SUCCEEDED