[11:16:52.464] New invocation is queued and will start shortly
[11:16:53.670] Starting the invocation (attempt 1)
[11:16:53.724] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:16:53.724] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:16:53.724] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9080367438654741968",
"parameters_json": "{\"builder_name\":\"Win32 Release (Clang)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src\",\"revision\":\"40409c141a762bd07463345da83128fe49d1ab88\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Win32 Release (Clang)",
"scheduler_invocation_id:9080367438654741968",
"scheduler_job_id:webrtc/Win32 Release (Clang)",
"user_agent:luci-scheduler",
"buildset:commit/git/40409c141a762bd07463345da83128fe49d1ab88",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/40409c141a762bd07463345da83128fe49d1ab88",
"gitiles_ref:refs/heads/master"
]
}
[11:16:54.312] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"created_by": "project:webrtc",
"created_ts": "1556450213827288",
"id": "8914937376544487200",
"parameters_json": "{\"builder_name\": \"Win32 Release (Clang)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"40409c141a762bd07463345da83128fe49d1ab88\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556450214207077",
"tags": [
"build_address:luci.webrtc.ci/Win32 Release (Clang)/16236",
"builder:Win32 Release (Clang)",
"buildset:commit/git/40409c141a762bd07463345da83128fe49d1ab88",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/40409c141a762bd07463345da83128fe49d1ab88",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080367438654741968",
"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/8914937376544487200/+/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": "1556450214207181",
"url": "https://ci.chromium.org/b/8914937376544487200",
"utcnow_ts": "1556450214295434"
}
}
[11:16:54.312] Task URL: https://ci.chromium.org/b/8914937376544487200
[11:16:54.312] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:2:0) after 1m0s
[11:16:57.746] Received PubSub notification, asking Buildbucket for the build status
[11:16:57.808] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:54.332] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:2:0)
[11:17:54.332] Timer tick, asking Buildbucket for the build status
[11:17:54.405] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:54.405] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:4:0) after 1m0s
[11:18:54.388] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:4:0)
[11:18:54.388] Timer tick, asking Buildbucket for the build status
[11:18:54.677] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:54.677] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:5:0) after 1m0s
[11:19:54.697] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:5:0)
[11:19:54.697] Timer tick, asking Buildbucket for the build status
[11:19:54.842] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:54.842] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:6:0) after 1m0s
[11:20:54.860] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:6:0)
[11:20:54.860] Timer tick, asking Buildbucket for the build status
[11:20:54.927] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:54.927] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:7:0) after 1m0s
[11:21:54.923] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:7:0)
[11:21:54.923] Timer tick, asking Buildbucket for the build status
[11:21:54.964] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:54.964] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:8:0) after 1m0s
[11:22:54.985] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:8:0)
[11:22:54.985] Timer tick, asking Buildbucket for the build status
[11:22:55.046] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:55.046] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:9:0) after 1m0s
[11:23:55.037] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:9:0)
[11:23:55.037] Timer tick, asking Buildbucket for the build status
[11:23:55.105] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:55.105] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:10:0) after 1m0s
[11:24:55.121] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:10:0)
[11:24:55.121] Timer tick, asking Buildbucket for the build status
[11:24:55.345] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:55.345] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:11:0) after 1m0s
[11:25:55.362] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:11:0)
[11:25:55.362] Timer tick, asking Buildbucket for the build status
[11:25:55.401] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:55.401] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:12:0) after 1m0s
[11:26:55.420] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:12:0)
[11:26:55.420] Timer tick, asking Buildbucket for the build status
[11:26:55.502] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:55.502] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:13:0) after 1m0s
[11:27:55.475] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:13:0)
[11:27:55.475] Timer tick, asking Buildbucket for the build status
[11:27:55.601] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:55.601] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:14:0) after 1m0s
[11:28:55.662] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:14:0)
[11:28:55.662] Timer tick, asking Buildbucket for the build status
[11:28:56.094] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:56.094] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:15:0) after 1m0s
[11:29:56.068] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:15:0)
[11:29:56.068] Timer tick, asking Buildbucket for the build status
[11:29:56.178] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:56.178] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:16:0) after 1m0s
[11:30:56.169] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:16:0)
[11:30:56.169] Timer tick, asking Buildbucket for the build status
[11:30:56.372] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:56.372] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:17:0) after 1m0s
[11:31:56.411] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:17:0)
[11:31:56.411] Timer tick, asking Buildbucket for the build status
[11:31:56.585] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:56.585] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:18:0) after 1m0s
[11:32:56.604] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:18:0)
[11:32:56.604] Timer tick, asking Buildbucket for the build status
[11:32:56.754] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:56.754] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:19:0) after 1m0s
[11:33:56.737] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:19:0)
[11:33:56.737] Timer tick, asking Buildbucket for the build status
[11:33:56.798] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:56.798] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:20:0) after 1m0s
[11:34:56.820] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:20:0)
[11:34:56.820] Timer tick, asking Buildbucket for the build status
[11:34:56.895] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:56.895] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:21:0) after 1m0s
[11:35:56.913] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:21:0)
[11:35:56.913] Timer tick, asking Buildbucket for the build status
[11:35:57.024] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:57.024] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:22:0) after 1m0s
[11:36:57.063] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:22:0)
[11:36:57.063] Timer tick, asking Buildbucket for the build status
[11:36:57.155] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:57.155] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:23:0) after 1m0s
[11:37:57.176] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:23:0)
[11:37:57.176] Timer tick, asking Buildbucket for the build status
[11:37:57.251] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:57.251] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:24:0) after 1m0s
[11:38:57.269] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:24:0)
[11:38:57.269] Timer tick, asking Buildbucket for the build status
[11:38:57.314] Build 8914937376544487200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:57.314] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9080367438654741968:25:0) after 1m0s
[11:39:09.470] Received PubSub notification, asking Buildbucket for the build status
[11:39:09.662] Build 8914937376544487200: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:39:09.662] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"completed_ts": "1556451548194875",
"created_by": "project:webrtc",
"created_ts": "1556450213827288",
"id": "8914937376544487200",
"parameters_json": "{\"builder_name\": \"Win32 Release (Clang)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"40409c141a762bd07463345da83128fe49d1ab88\"}}",
"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-10-9xxj\", \"branch\": \"refs/heads/master\", \"buildername\": \"Win32 Release (Clang)\", \"buildnumber\": 16236, \"got_clang_revision\": \"357692-1\", \"got_revision\": \"40409c141a762bd07463345da83128fe49d1ab88\", \"got_revision_cp\": \"refs/heads/master@{#27795}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.webrtc\", \"path_config\": \"generic\", \"recipe\": \"webrtc/standalone\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"40409c141a762bd07463345da83128fe49d1ab88\", \"swarm_hashes\": {\"audio_decoder_unittests\": \"d18586f69b9c35ae2aed0e420d27dfe10c4969f6\", \"common_audio_unittests\": \"92253a215dd41f56df0ddfa42455ba46d9f4e8b4\", \"common_video_unittests\": \"2d0fe9efaf148f00c8804dbb73e842ac2dae5dc1\", \"low_bandwidth_audio_test\": \"62b9b3eaecb2658c596654f989e9e5ad165d8d09\", \"modules_tests\": \"6e87cadc1ea1e1e33a892d99d267e7763c74fc17\", \"modules_unittests\": \"dba357c783d2228e3aa8114f0b2bdf75158baa9c\", \"peerconnection_unittests\": \"20320b4e3a1181fe696ba21b78a772a330c718b4\", \"rtc_media_unittests\": \"4eb62107d2bdf5bf870fa368b1939d7eebd08c4e\", \"rtc_pc_unittests\": \"d403e0ec8e45eaba3e0c04c822a83108230d4179\", \"rtc_stats_unittests\": \"ff21cdf3cedbae29113aaaff8a2e808d374efdb0\", \"rtc_unittests\": \"f1295bab3a87e485764138b8f6ef142b222c4dfa\", \"slow_tests\": \"96e3eef0d038e263465707c38ec8046b784a3979\", \"system_wrappers_unittests\": \"b3aedc3858ad4559c4830a8101a73874b062ad96\", \"test_support_unittests\": \"8c1594367329f558d0d297562cb86932e817e6f7\", \"tools_unittests\": \"ecac6d1ec9986053f8945313307956af30d9500d\", \"video_capture_tests\": \"63ce33be313b826e4fd8abff8c74a25edc7a0bd4\", \"video_engine_tests\": \"cd44fd2933ce9a3fd3d6fdf8f16c95cdcb48f00f\", \"webrtc_nonparallel_tests\": \"2cd3eb9a45796731eb749f202380309fedb835dd\"}}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-webrtc-ci-win10-10-9xxj\"], \"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\": [\"4274-a0a5f8c\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1556450217081019",
"status": "COMPLETED",
"status_changed_ts": "1556451549229964",
"tags": [
"build_address:luci.webrtc.ci/Win32 Release (Clang)/16236",
"builder:Win32 Release (Clang)",
"buildset:commit/git/40409c141a762bd07463345da83128fe49d1ab88",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/40409c141a762bd07463345da83128fe49d1ab88",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080367438654741968",
"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/8914937376544487200/+/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:447c7b0a6045a910",
"user_agent:luci-scheduler"
],
"updated_ts": "1556451549246744",
"url": "https://ci.chromium.org/b/8914937376544487200",
"utcnow_ts": "1556451549653033"
}
}
[11:39:09.662] Invocation finished in 22m17.231218368s with status SUCCEEDED