[09:31:48.940] New invocation is queued and will start shortly
[09:31:51.658] Starting the invocation (attempt 1)
[09:31:51.692] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:31:51.692] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:31:51.692] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9079015093870182048",
"parameters_json": "{\"builder_name\":\"Linux MSan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src\",\"revision\":\"d7c7d0ed883c286f648cbbb22b46cf4e84be9c95\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux MSan",
"scheduler_invocation_id:9079015093870182048",
"scheduler_job_id:webrtc/Linux MSan",
"user_agent:luci-scheduler",
"buildset:commit/git/d7c7d0ed883c286f648cbbb22b46cf4e84be9c95",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/d7c7d0ed883c286f648cbbb22b46cf4e84be9c95",
"gitiles_ref:refs/heads/master"
]
}
[09:31:55.466] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"created_by": "project:webrtc",
"created_ts": "1557739911998191",
"id": "8913585029995297072",
"parameters_json": "{\"builder_name\": \"Linux MSan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"d7c7d0ed883c286f648cbbb22b46cf4e84be9c95\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557739914316364",
"tags": [
"build_address:luci.webrtc.ci/Linux MSan/18886",
"builder:Linux MSan",
"buildset:commit/git/d7c7d0ed883c286f648cbbb22b46cf4e84be9c95",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/d7c7d0ed883c286f648cbbb22b46cf4e84be9c95",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079015093870182048",
"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/8913585029995297072/+/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": "1557739914316566",
"url": "https://ci.chromium.org/b/8913585029995297072",
"utcnow_ts": "1557739915453111"
}
}
[09:31:55.466] Task URL: https://ci.chromium.org/b/8913585029995297072
[09:31:55.466] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:2:0) after 1m0s
[09:32:55.466] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:2:0)
[09:32:55.466] Timer tick, asking Buildbucket for the build status
[09:32:55.583] Build 8913585029995297072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:32:55.583] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:3:0) after 1m0s
[09:33:55.601] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:3:0)
[09:33:55.601] Timer tick, asking Buildbucket for the build status
[09:33:55.807] Build 8913585029995297072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:33:55.807] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:4:0) after 1m0s
[09:34:55.840] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:4:0)
[09:34:55.840] Timer tick, asking Buildbucket for the build status
[09:34:56.017] Build 8913585029995297072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:34:56.017] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:5:0) after 1m0s
[09:35:56.054] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:5:0)
[09:35:56.054] Timer tick, asking Buildbucket for the build status
[09:35:56.468] Build 8913585029995297072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:35:56.468] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:6:0) after 1m0s
[09:36:56.494] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:6:0)
[09:36:56.494] Timer tick, asking Buildbucket for the build status
[09:36:56.621] Build 8913585029995297072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:36:56.621] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:7:0) after 1m0s
[09:37:56.647] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:7:0)
[09:37:56.647] Timer tick, asking Buildbucket for the build status
[09:37:56.833] Build 8913585029995297072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:37:56.833] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:8:0) after 1m0s
[09:38:56.827] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:8:0)
[09:38:56.827] Timer tick, asking Buildbucket for the build status
[09:38:57.191] Build 8913585029995297072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:38:57.191] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:9:0) after 1m0s
[09:39:57.255] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:9:0)
[09:39:57.255] Timer tick, asking Buildbucket for the build status
[09:39:57.350] Build 8913585029995297072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:39:57.350] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:10:0) after 1m0s
[09:40:57.369] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:10:0)
[09:40:57.369] Timer tick, asking Buildbucket for the build status
[09:40:57.588] Build 8913585029995297072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:40:57.588] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:11:0) after 1m0s
[09:41:38.044] Received PubSub notification, asking Buildbucket for the build status
[09:41:38.503] Build 8913585029995297072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:57.606] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:11:0)
[09:41:57.606] Timer tick, asking Buildbucket for the build status
[09:41:57.866] Build 8913585029995297072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:57.866] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:13:0) after 1m0s
[09:42:58.135] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:13:0)
[09:42:58.135] Timer tick, asking Buildbucket for the build status
[09:42:58.248] Build 8913585029995297072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:58.248] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:14:0) after 1m0s
[09:43:58.493] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:14:0)
[09:43:58.493] Timer tick, asking Buildbucket for the build status
[09:43:58.559] Build 8913585029995297072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:58.559] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:15:0) after 1m0s
[09:44:58.612] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:15:0)
[09:44:58.612] Timer tick, asking Buildbucket for the build status
[09:44:59.152] Build 8913585029995297072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:59.152] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:16:0) after 1m0s
[09:45:59.177] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:16:0)
[09:45:59.177] Timer tick, asking Buildbucket for the build status
[09:45:59.463] Build 8913585029995297072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:59.463] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:17:0) after 1m0s
[09:46:59.486] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:17:0)
[09:46:59.486] Timer tick, asking Buildbucket for the build status
[09:46:59.557] Build 8913585029995297072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:59.557] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:18:0) after 1m0s
[09:47:59.580] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:18:0)
[09:47:59.580] Timer tick, asking Buildbucket for the build status
[09:47:59.816] Build 8913585029995297072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:59.816] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:19:0) after 1m0s
[09:48:59.800] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:19:0)
[09:48:59.800] Timer tick, asking Buildbucket for the build status
[09:49:01.187] Build 8913585029995297072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:01.187] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:20:0) after 1m0s
[09:50:01.629] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:20:0)
[09:50:01.629] Timer tick, asking Buildbucket for the build status
[09:50:01.863] Build 8913585029995297072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:01.863] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:21:0) after 1m0s
[09:51:02.394] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:21:0)
[09:51:02.394] Timer tick, asking Buildbucket for the build status
[09:51:02.580] Build 8913585029995297072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:02.580] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:22:0) after 1m0s
[09:52:02.942] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:22:0)
[09:52:02.942] Timer tick, asking Buildbucket for the build status
[09:52:03.474] Build 8913585029995297072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:03.474] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:23:0) after 1m0s
[09:53:03.494] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:23:0)
[09:53:03.494] Timer tick, asking Buildbucket for the build status
[09:53:03.604] Build 8913585029995297072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:03.604] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9079015093870182048:24:0) after 1m0s
[09:53:35.662] Received PubSub notification, asking Buildbucket for the build status
[09:53:36.031] Build 8913585029995297072: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:53:36.031] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"completed_ts": "1557741211389216",
"created_by": "project:webrtc",
"created_ts": "1557739911998191",
"id": "8913585029995297072",
"parameters_json": "{\"builder_name\": \"Linux MSan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"d7c7d0ed883c286f648cbbb22b46cf4e84be9c95\"}}",
"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-10-b5is\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux MSan\", \"buildnumber\": 18886, \"got_clang_revision\": \"360094-5\", \"got_revision\": \"d7c7d0ed883c286f648cbbb22b46cf4e84be9c95\", \"got_revision_cp\": \"refs/heads/master@{#27922}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.webrtc\", \"path_config\": \"generic\", \"recipe\": \"webrtc/standalone\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"d7c7d0ed883c286f648cbbb22b46cf4e84be9c95\", \"swarm_hashes\": {\"audio_decoder_unittests\": \"72e80f5572573d22736fec8b35c5fc78515d878a\", \"common_audio_unittests\": \"233ba62bd25f4b2280e042afbdc24769f4a388bc\", \"common_video_unittests\": \"66ea239e98738fe06ec918bba306810944963512\", \"low_bandwidth_audio_test\": \"7e41b0bc2aaf04f7e4aaaf27077bebf5d649a1bd\", \"modules_tests\": \"214f0d36f91bc02eae68ad79bebc4e62b1eb550f\", \"modules_unittests\": \"afcb790b3ba74afca8f802d956855a462f8a3514\", \"peerconnection_unittests\": \"cf9dd28e7df899b6ef5709c572c509fe7cd95371\", \"rtc_media_unittests\": \"e2ceee3823308522a3f4b90f17c480de0bbbadb7\", \"rtc_pc_unittests\": \"4860c971a9769e508cfedbeda5a558526e41f1de\", \"rtc_stats_unittests\": \"f47e7398593e3ce16eeb5b1eb875490b9df8c9f8\", \"rtc_unittests\": \"7b3784aac82f800ccff3ff72fd6c82880ce6f7db\", \"slow_tests\": \"77f63c80e77333c598a5d25941cde5af9c2590cb\", \"system_wrappers_unittests\": \"c0da72617864700fd5db301a14321060d54832f3\", \"test_support_unittests\": \"b04c45338a4632e09616f7f336d2c9e7c878650f\", \"tools_unittests\": \"6a4a7e927f4177ee1e5681b23ddb83bf4be468fb\", \"video_engine_tests\": \"35ff99f1cd60c74ad8ed4ba3f5a91c19b74330a0\", \"webrtc_nonparallel_tests\": \"e783bc6ba840d40363628216b94ec961d5130924\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_44678c1ef4c7d705bd7e592df97e9c1573c4ed9129da6654b149d3b42d045362_v2\", \"builder_55aa4c084be5ed9d3954314e1098bc0d32b90f37d826af3d055ec49a08faf339_v2\", \"builder_c07c6aa6d1c1b67d8a4e8f97b8bdf68f3af1543bb4dcfc73adac20d8532d9b4f_v2\", \"builder_ee80cf8bba7c81fee9e980afd619ea0acf20624840b3b5e7cf3f2ef0d6b70dc4_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-10-b5is\"], \"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\": [\"4292-d8c05fa\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1557740496609085",
"status": "COMPLETED",
"status_changed_ts": "1557741213908873",
"tags": [
"build_address:luci.webrtc.ci/Linux MSan/18886",
"builder:Linux MSan",
"buildset:commit/git/d7c7d0ed883c286f648cbbb22b46cf4e84be9c95",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/d7c7d0ed883c286f648cbbb22b46cf4e84be9c95",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079015093870182048",
"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/8913585029995297072/+/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:44c95a4fd5400510",
"user_agent:luci-scheduler"
],
"updated_ts": "1557741213982950",
"url": "https://ci.chromium.org/b/8913585029995297072",
"utcnow_ts": "1557741215992886"
}
}
[09:53:36.031] Invocation finished in 21m47.111622178s with status SUCCEEDED