[22:46:18.841] New invocation is queued and will start shortly
[22:46:21.597] Starting the invocation (attempt 1)
[22:46:21.643] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:46:21.643] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:46:21.643] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9074797647916232752",
"parameters_json": "{\"builder_name\":\"Linux MSan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src\",\"revision\":\"65764e4ed7174849b0ad89475bf0c91b2f566a14\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux MSan",
"scheduler_invocation_id:9074797647916232752",
"scheduler_job_id:webrtc/Linux MSan",
"user_agent:luci-scheduler",
"buildset:commit/git/65764e4ed7174849b0ad89475bf0c91b2f566a14",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/65764e4ed7174849b0ad89475bf0c91b2f566a14",
"gitiles_ref:refs/heads/master"
]
}
[22:46:23.474] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"created_by": "project:webrtc",
"created_ts": "1561761981820460",
"id": "8909367584109531056",
"parameters_json": "{\"builder_name\": \"Linux MSan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"65764e4ed7174849b0ad89475bf0c91b2f566a14\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1561761983253626",
"tags": [
"build_address:luci.webrtc.ci/Linux MSan/19371",
"builder:Linux MSan",
"buildset:commit/git/65764e4ed7174849b0ad89475bf0c91b2f566a14",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/65764e4ed7174849b0ad89475bf0c91b2f566a14",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9074797647916232752",
"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/8909367584109531056/+/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": "1561761983253719",
"url": "https://ci.chromium.org/b/8909367584109531056",
"utcnow_ts": "1561761983458100"
}
}
[22:46:23.474] Task URL: https://ci.chromium.org/b/8909367584109531056
[22:46:23.474] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:2:0) after 1m0s
[22:47:23.548] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:2:0)
[22:47:23.548] Timer tick, asking Buildbucket for the build status
[22:47:23.640] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:23.640] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:3:0) after 1m0s
[22:48:23.751] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:3:0)
[22:48:23.751] Timer tick, asking Buildbucket for the build status
[22:48:23.842] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:23.842] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:4:0) after 1m0s
[22:49:24.140] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:4:0)
[22:49:24.140] Timer tick, asking Buildbucket for the build status
[22:49:24.253] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:24.253] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:5:0) after 1m0s
[22:50:24.497] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:5:0)
[22:50:24.497] Timer tick, asking Buildbucket for the build status
[22:50:25.454] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:25.454] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:6:0) after 1m0s
[22:51:25.678] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:6:0)
[22:51:25.678] Timer tick, asking Buildbucket for the build status
[22:51:25.795] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:25.795] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:7:0) after 1m0s
[22:52:26.076] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:7:0)
[22:52:26.076] Timer tick, asking Buildbucket for the build status
[22:52:26.203] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:26.203] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:8:0) after 1m0s
[22:53:27.252] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:8:0)
[22:53:27.252] Timer tick, asking Buildbucket for the build status
[22:53:27.359] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:27.359] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:9:0) after 1m0s
[22:54:27.489] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:9:0)
[22:54:27.489] Timer tick, asking Buildbucket for the build status
[22:54:27.592] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:27.592] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:10:0) after 1m0s
[22:55:28.802] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:10:0)
[22:55:28.802] Timer tick, asking Buildbucket for the build status
[22:55:29.194] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:29.194] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:11:0) after 1m0s
[22:56:29.210] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:11:0)
[22:56:29.210] Timer tick, asking Buildbucket for the build status
[22:56:29.446] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:29.446] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:12:0) after 1m0s
[22:57:29.500] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:12:0)
[22:57:29.500] Timer tick, asking Buildbucket for the build status
[22:57:30.360] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:30.360] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:13:0) after 1m0s
[22:58:30.378] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:13:0)
[22:58:30.378] Timer tick, asking Buildbucket for the build status
[22:58:30.473] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:30.473] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:14:0) after 1m0s
[22:59:30.497] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:14:0)
[22:59:30.497] Timer tick, asking Buildbucket for the build status
[22:59:30.711] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:30.711] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:15:0) after 1m0s
[23:00:30.807] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:15:0)
[23:00:30.807] Timer tick, asking Buildbucket for the build status
[23:00:31.019] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:31.019] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:16:0) after 1m0s
[23:01:31.057] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:16:0)
[23:01:31.058] Timer tick, asking Buildbucket for the build status
[23:01:31.189] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:31.189] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:17:0) after 1m0s
[23:02:31.350] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:17:0)
[23:02:31.350] Timer tick, asking Buildbucket for the build status
[23:02:31.485] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:31.485] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:18:0) after 1m0s
[23:03:31.567] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:18:0)
[23:03:31.567] Timer tick, asking Buildbucket for the build status
[23:03:31.768] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:31.768] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:19:0) after 1m0s
[23:04:31.933] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:19:0)
[23:04:31.933] Timer tick, asking Buildbucket for the build status
[23:04:32.040] Build 8909367584109531056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:32.040] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:20:0) after 1m0s
[23:04:34.882] Received PubSub notification, asking Buildbucket for the build status
[23:04:34.953] Build 8909367584109531056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:32.107] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:20:0)
[23:05:32.107] Timer tick, asking Buildbucket for the build status
[23:05:32.375] Build 8909367584109531056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:32.375] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:22:0) after 1m0s
[23:06:32.465] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:22:0)
[23:06:32.465] Timer tick, asking Buildbucket for the build status
[23:06:32.595] Build 8909367584109531056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:32.595] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:23:0) after 1m0s
[23:07:32.661] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:23:0)
[23:07:32.661] Timer tick, asking Buildbucket for the build status
[23:07:32.733] Build 8909367584109531056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:32.733] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:24:0) after 1m0s
[23:08:32.733] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:24:0)
[23:08:32.733] Timer tick, asking Buildbucket for the build status
[23:08:34.880] Build 8909367584109531056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:34.880] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:25:0) after 1m0s
[23:09:34.917] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:25:0)
[23:09:34.917] Timer tick, asking Buildbucket for the build status
[23:09:35.015] Build 8909367584109531056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:35.015] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:26:0) after 1m0s
[23:10:35.183] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:26:0)
[23:10:35.183] Timer tick, asking Buildbucket for the build status
[23:10:35.279] Build 8909367584109531056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:35.279] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:27:0) after 1m0s
[23:11:35.328] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:27:0)
[23:11:35.328] Timer tick, asking Buildbucket for the build status
[23:11:35.630] Build 8909367584109531056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:35.630] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:28:0) after 1m0s
[23:12:35.764] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:28:0)
[23:12:35.764] Timer tick, asking Buildbucket for the build status
[23:12:35.962] Build 8909367584109531056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:35.962] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:29:0) after 1m0s
[23:13:36.256] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:29:0)
[23:13:36.256] Timer tick, asking Buildbucket for the build status
[23:13:36.406] Build 8909367584109531056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:36.406] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:30:0) after 1m0s
[23:14:36.421] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:30:0)
[23:14:36.421] Timer tick, asking Buildbucket for the build status
[23:14:37.154] Build 8909367584109531056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:37.154] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:31:0) after 1m0s
[23:15:37.230] Handling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:31:0)
[23:15:37.230] Timer tick, asking Buildbucket for the build status
[23:15:37.352] Build 8909367584109531056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:37.352] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux MSan:9074797647916232752:32:0) after 1m0s
[23:16:18.586] Received PubSub notification, asking Buildbucket for the build status
[23:16:18.772] Build 8909367584109531056: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:16:18.772] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"completed_ts": "1561763777756312",
"created_by": "project:webrtc",
"created_ts": "1561761981820460",
"id": "8909367584109531056",
"parameters_json": "{\"builder_name\": \"Linux MSan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"65764e4ed7174849b0ad89475bf0c91b2f566a14\"}}",
"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-7-o9n7\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux MSan\", \"buildnumber\": 19371, \"got_clang_revision\": \"363790-d874c057-3\", \"got_revision\": \"65764e4ed7174849b0ad89475bf0c91b2f566a14\", \"got_revision_cp\": \"refs/heads/master@{#28423}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.webrtc\", \"path_config\": \"generic\", \"recipe\": \"webrtc/standalone\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"65764e4ed7174849b0ad89475bf0c91b2f566a14\", \"swarm_hashes\": {\"audio_decoder_unittests\": \"b884de47e9cf27f52d18e152cdf77e55a501d5d7\", \"common_audio_unittests\": \"2d3e81083fa0ef37254757dd4cd9a3b2a8b1f705\", \"common_video_unittests\": \"44878ecdac281a86fee9daa35c9d7cc10d1fb1f5\", \"low_bandwidth_audio_test\": \"69f6ee2d55c56c04c42b667ba6a63f7476ee42d6\", \"modules_tests\": \"7cc52e017492403d952795762b35c56992932db2\", \"modules_unittests\": \"2c28687ae8e9a7de4287f1abecebf3fbe9e58102\", \"peerconnection_unittests\": \"e222159344f27cbb7f044360fb5fcb018f0dc986\", \"rtc_media_unittests\": \"244588db417976bbf5b0db834de1c1d8ff014e51\", \"rtc_pc_unittests\": \"b6c46bcc1faf281f04b7ac08c520ec2d86b57b1c\", \"rtc_stats_unittests\": \"a2a6b4e6cfaa24003526b81697c8a3783e91b285\", \"rtc_unittests\": \"bda3e2aaded930a8926ceaf5431457573f0783a5\", \"slow_tests\": \"0bcb0aa2a049373cad6460cd89165342fe8359f1\", \"system_wrappers_unittests\": \"0f60f1e0f31e689f16ee72f9eda5ef9c07e9e6fb\", \"test_support_unittests\": \"3290332c9d3a077520f982519f956d9dc4212d8f\", \"tools_unittests\": \"3a01ba9e69f31e2a014200131c328dee674b7376\", \"video_engine_tests\": \"e18f474126b3923aa332dd3f635736203036d56b\", \"webrtc_nonparallel_tests\": \"6da250289c42fe4ef003744af6a1bcd0c334e486\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"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-7-o9n7\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"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\": [\"4351-b85b41c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1561763073057774",
"status": "COMPLETED",
"status_changed_ts": "1561763778042895",
"tags": [
"build_address:luci.webrtc.ci/Linux MSan/19371",
"builder:Linux MSan",
"buildset:commit/git/65764e4ed7174849b0ad89475bf0c91b2f566a14",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/65764e4ed7174849b0ad89475bf0c91b2f566a14",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9074797647916232752",
"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/8909367584109531056/+/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:45b9163598522c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1561763778055699",
"url": "https://ci.chromium.org/b/8909367584109531056",
"utcnow_ts": "1561763778762203"
}
}
[23:16:18.772] Invocation finished in 29m59.966752755s with status SUCCEEDED