[11:06:04.384] New invocation is queued and will start shortly
[11:06:06.089] Starting the invocation (attempt 1)
[11:06:06.179] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:06:06.180] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:06:06.180] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9075294688079369056",
"parameters_json": "{\"builder_name\":\"Win32 Release (Clang)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src\",\"revision\":\"214f54365ec210db76218a35ead66c9ce23e068e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Win32 Release (Clang)",
"scheduler_invocation_id:9075294688079369056",
"scheduler_job_id:webrtc/Win32 Release (Clang)",
"user_agent:luci-scheduler",
"buildset:commit/git/214f54365ec210db76218a35ead66c9ce23e068e",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/214f54365ec210db76218a35ead66c9ce23e068e",
"gitiles_ref:refs/heads/master"
]
}
[11:06:07.405] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"created_by": "project:webrtc",
"created_ts": "1561287966318023",
"id": "8909864625388497344",
"parameters_json": "{\"builder_name\": \"Win32 Release (Clang)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"214f54365ec210db76218a35ead66c9ce23e068e\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1561287967113280",
"tags": [
"build_address:luci.webrtc.ci/Win32 Release (Clang)/16774",
"builder:Win32 Release (Clang)",
"buildset:commit/git/214f54365ec210db76218a35ead66c9ce23e068e",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/214f54365ec210db76218a35ead66c9ce23e068e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9075294688079369056",
"scheduler_job_id:webrtc/Win32 Release (Clang)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8909864625388497344/+/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": "1561287967113369",
"url": "https://ci.chromium.org/b/8909864625388497344",
"utcnow_ts": "1561287967351871"
}
}
[11:06:07.405] Task URL: https://ci.chromium.org/b/8909864625388497344
[11:06:07.405] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:2:0) after 1m0s
[11:07:01.738] Received PubSub notification, asking Buildbucket for the build status
[11:07:01.836] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:07.946] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:2:0)
[11:07:07.946] Timer tick, asking Buildbucket for the build status
[11:07:08.024] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:08.024] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:4:0) after 1m0s
[11:08:08.359] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:4:0)
[11:08:08.359] Timer tick, asking Buildbucket for the build status
[11:08:08.562] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:08.562] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:5:0) after 1m0s
[11:09:08.595] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:5:0)
[11:09:08.595] Timer tick, asking Buildbucket for the build status
[11:09:08.808] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:08.808] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:6:0) after 1m0s
[11:10:09.160] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:6:0)
[11:10:09.160] Timer tick, asking Buildbucket for the build status
[11:10:09.279] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:09.279] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:7:0) after 1m0s
[11:11:09.625] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:7:0)
[11:11:09.625] Timer tick, asking Buildbucket for the build status
[11:11:09.920] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:09.920] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:8:0) after 1m0s
[11:12:10.210] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:8:0)
[11:12:10.210] Timer tick, asking Buildbucket for the build status
[11:12:10.410] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:10.410] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:9:0) after 1m0s
[11:13:10.678] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:9:0)
[11:13:10.678] Timer tick, asking Buildbucket for the build status
[11:13:10.810] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:10.810] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:10:0) after 1m0s
[11:14:10.966] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:10:0)
[11:14:10.966] Timer tick, asking Buildbucket for the build status
[11:14:11.398] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:11.398] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:11:0) after 1m0s
[11:15:11.473] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:11:0)
[11:15:11.473] Timer tick, asking Buildbucket for the build status
[11:15:11.542] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:11.542] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:12:0) after 1m0s
[11:16:11.686] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:12:0)
[11:16:11.686] Timer tick, asking Buildbucket for the build status
[11:16:12.070] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:12.070] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:13:0) after 1m0s
[11:17:12.560] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:13:0)
[11:17:12.560] Timer tick, asking Buildbucket for the build status
[11:17:12.658] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:12.658] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:14:0) after 1m0s
[11:18:12.734] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:14:0)
[11:18:12.734] Timer tick, asking Buildbucket for the build status
[11:18:12.882] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:12.882] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:15:0) after 1m0s
[11:19:13.140] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:15:0)
[11:19:13.140] Timer tick, asking Buildbucket for the build status
[11:19:13.244] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:13.244] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:16:0) after 1m0s
[11:20:13.268] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:16:0)
[11:20:13.268] Timer tick, asking Buildbucket for the build status
[11:20:13.625] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:13.625] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:17:0) after 1m0s
[11:21:13.790] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:17:0)
[11:21:13.790] Timer tick, asking Buildbucket for the build status
[11:21:13.960] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:13.960] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:18:0) after 1m0s
[11:22:14.044] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:18:0)
[11:22:14.044] Timer tick, asking Buildbucket for the build status
[11:22:14.205] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:14.205] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:19:0) after 1m0s
[11:23:14.229] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:19:0)
[11:23:14.229] Timer tick, asking Buildbucket for the build status
[11:23:14.353] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:14.353] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:20:0) after 1m0s
[11:24:14.379] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:20:0)
[11:24:14.379] Timer tick, asking Buildbucket for the build status
[11:24:14.478] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:14.478] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:21:0) after 1m0s
[11:25:14.506] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:21:0)
[11:25:14.506] Timer tick, asking Buildbucket for the build status
[11:25:14.633] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:14.633] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:22:0) after 1m0s
[11:26:14.662] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:22:0)
[11:26:14.662] Timer tick, asking Buildbucket for the build status
[11:26:14.834] Build 8909864625388497344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:14.834] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9075294688079369056:23:0) after 1m0s
[11:26:56.857] Received PubSub notification, asking Buildbucket for the build status
[11:26:56.925] Build 8909864625388497344: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:26:56.925] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"completed_ts": "1561289215860522",
"created_by": "project:webrtc",
"created_ts": "1561287966318023",
"id": "8909864625388497344",
"parameters_json": "{\"builder_name\": \"Win32 Release (Clang)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"214f54365ec210db76218a35ead66c9ce23e068e\"}}",
"project": "webrtc",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-webrtc-ci-win10-9-zihn\", \"branch\": \"refs/heads/master\", \"buildername\": \"Win32 Release (Clang)\", \"buildnumber\": 16774, \"got_clang_revision\": \"363790-d874c057-2\", \"got_revision\": \"214f54365ec210db76218a35ead66c9ce23e068e\", \"got_revision_cp\": \"refs/heads/master@{#28343}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.webrtc\", \"path_config\": \"generic\", \"recipe\": \"webrtc/standalone\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"214f54365ec210db76218a35ead66c9ce23e068e\", \"swarm_hashes\": {\"audio_decoder_unittests\": \"64a48265f8800888bf5e63c2fe9926475a174586\", \"common_audio_unittests\": \"349ee5779439cef52b3b540430e8c3e39f732703\", \"common_video_unittests\": \"19f59df625123788adcd61a64baed62e3eedd2d6\", \"low_bandwidth_audio_test\": \"fb4bd8b844f62894efd46266eccfb095c0a14eb4\", \"modules_tests\": \"deedcd9abd531548cb40f7254faaaa7e737f221f\", \"modules_unittests\": \"bd89e73c20ab49bf7e8f5cd15c0a89a8e1d6326a\", \"peerconnection_unittests\": \"868735291d923a999594d20ab31ce56814b87db7\", \"rtc_media_unittests\": \"070bf95bca19e3e75b7c9d75cfbd1b0a287d2dd6\", \"rtc_pc_unittests\": \"efa5fc22e1b79067391731659eb0356b96551fed\", \"rtc_stats_unittests\": \"f51ce005e1d7f3271a31f942c447c21fad8f3d73\", \"rtc_unittests\": \"d703b966525a58162a655588fb5c8ccddb3ea154\", \"slow_tests\": \"3089101e68a412d8149a2d08ef251d5eaddc7cdb\", \"system_wrappers_unittests\": \"7299f74c07d360271817835294fdf5ae995a38d2\", \"test_support_unittests\": \"1874022d3e502234a85811a6ff8db4f82c76a196\", \"tools_unittests\": \"daf3ed424e2435e558dbaefac118e0b9485800fa\", \"video_capture_tests\": \"22b011ca5cdc2768d174e3af8b76f68fc8c4a3ff\", \"video_engine_tests\": \"fe2eb7caf11e17148ccd8403b5113331256de145\", \"webrtc_nonparallel_tests\": \"a61328fa2358636d34373951243890fe26b2ea6f\"}}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-webrtc-ci-win10-9-zihn\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.webrtc.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4339-6285c57\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1561288020036880",
"status": "COMPLETED",
"status_changed_ts": "1561289216105234",
"tags": [
"build_address:luci.webrtc.ci/Win32 Release (Clang)/16774",
"builder:Win32 Release (Clang)",
"buildset:commit/git/214f54365ec210db76218a35ead66c9ce23e068e",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/214f54365ec210db76218a35ead66c9ce23e068e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9075294688079369056",
"scheduler_job_id:webrtc/Win32 Release (Clang)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8909864625388497344/+/annotations",
"swarming_tag:luci_project:webrtc",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:webrtc/standalone",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:459cd54e43b13510",
"user_agent:luci-scheduler"
],
"updated_ts": "1561289216107672",
"url": "https://ci.chromium.org/b/8909864625388497344",
"utcnow_ts": "1561289216912546"
}
}
[11:26:56.925] Invocation finished in 20m52.557362616s with status SUCCEEDED