[10:11:30.738] New invocation is queued and will start shortly
[10:11:32.214] Starting the invocation (attempt 1)
[10:11:32.254] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:11:32.254] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:11:32.254] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9041505452285565856",
"parameters_json": "{\"builder_name\":\"Win32 Release (Clang)\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://webrtc.googlesource.com/src\",\"revision\":\"4b5792cc4a19502e46e46a257945c27bf2205055\"},\"id\":\"https://webrtc.googlesource.com/src/+/refs/heads/master@4b5792cc4a19502e46e46a257945c27bf2205055\",\"title\":\"4b5792cc4a19502e46e46a257945c27bf2205055\",\"url\":\"https://webrtc.googlesource.com/src/+/4b5792cc4a19502e46e46a257945c27bf2205055\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src\",\"revision\":\"4b5792cc4a19502e46e46a257945c27bf2205055\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Win32 Release (Clang)",
"scheduler_invocation_id:9041505452285565856",
"scheduler_job_id:webrtc/Win32 Release (Clang)",
"user_agent:luci-scheduler",
"buildset:commit/git/4b5792cc4a19502e46e46a257945c27bf2205055",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/4b5792cc4a19502e46e46a257945c27bf2205055",
"gitiles_ref:refs/heads/master"
]
}
[10:11:33.027] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"created_by": "project:webrtc",
"created_ts": "1593511892360479",
"id": "8876075389915403792",
"parameters_json": "{\"builder_name\": \"Win32 Release (Clang)\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://webrtc.googlesource.com/src\", \"revision\": \"4b5792cc4a19502e46e46a257945c27bf2205055\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@4b5792cc4a19502e46e46a257945c27bf2205055\", \"title\": \"4b5792cc4a19502e46e46a257945c27bf2205055\", \"url\": \"https://webrtc.googlesource.com/src/+/4b5792cc4a19502e46e46a257945c27bf2205055\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"4b5792cc4a19502e46e46a257945c27bf2205055\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1593511892741365",
"tags": [
"build_address:luci.webrtc.ci/Win32 Release (Clang)/19929",
"builder:Win32 Release (Clang)",
"buildset:commit/git/4b5792cc4a19502e46e46a257945c27bf2205055",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/4b5792cc4a19502e46e46a257945c27bf2205055",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9041505452285565856",
"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/8876075389915403792/+/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": "1593511892741507",
"url": "https://ci.chromium.org/b/8876075389915403792",
"utcnow_ts": "1593511893019878"
}
}
[10:11:33.027] Task URL: https://ci.chromium.org/b/8876075389915403792
[10:11:33.027] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:2:0) after 1m0s
[10:12:33.039] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:2:0)
[10:12:33.039] Timer tick, asking Buildbucket for the build status
[10:12:33.457] Build 8876075389915403792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:12:33.457] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:3:0) after 1m0s
[10:12:44.998] Received PubSub notification, asking Buildbucket for the build status
[10:12:45.092] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:33.488] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:3:0)
[10:13:33.488] Timer tick, asking Buildbucket for the build status
[10:13:35.009] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:35.009] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:5:0) after 1m0s
[10:14:35.043] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:5:0)
[10:14:35.043] Timer tick, asking Buildbucket for the build status
[10:14:36.904] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:36.904] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:6:0) after 1m0s
[10:15:37.077] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:6:0)
[10:15:37.077] Timer tick, asking Buildbucket for the build status
[10:15:37.204] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:37.205] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:7:0) after 1m0s
[10:16:37.276] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:7:0)
[10:16:37.276] Timer tick, asking Buildbucket for the build status
[10:16:37.422] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:37.422] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:8:0) after 1m0s
[10:17:37.552] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:8:0)
[10:17:37.552] Timer tick, asking Buildbucket for the build status
[10:17:37.686] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:37.686] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:9:0) after 1m0s
[10:18:37.725] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:9:0)
[10:18:37.725] Timer tick, asking Buildbucket for the build status
[10:18:37.839] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:37.839] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:10:0) after 1m0s
[10:19:37.861] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:10:0)
[10:19:37.861] Timer tick, asking Buildbucket for the build status
[10:19:42.923] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:42.923] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:11:0) after 1m0s
[10:20:42.995] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:11:0)
[10:20:42.995] Timer tick, asking Buildbucket for the build status
[10:20:43.196] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:43.196] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:12:0) after 1m0s
[10:21:43.274] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:12:0)
[10:21:43.274] Timer tick, asking Buildbucket for the build status
[10:21:43.434] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:43.434] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:13:0) after 1m0s
[10:22:43.463] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:13:0)
[10:22:43.463] Timer tick, asking Buildbucket for the build status
[10:22:43.617] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:43.617] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:14:0) after 1m0s
[10:23:43.682] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:14:0)
[10:23:43.682] Timer tick, asking Buildbucket for the build status
[10:23:44.253] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:44.254] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:15:0) after 1m0s
[10:24:44.381] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:15:0)
[10:24:44.381] Timer tick, asking Buildbucket for the build status
[10:24:45.367] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:45.367] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:16:0) after 1m0s
[10:25:45.367] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:16:0)
[10:25:45.367] Timer tick, asking Buildbucket for the build status
[10:25:45.495] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:45.495] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:17:0) after 1m0s
[10:26:45.537] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:17:0)
[10:26:45.537] Timer tick, asking Buildbucket for the build status
[10:26:45.684] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:45.684] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:18:0) after 1m0s
[10:27:45.732] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:18:0)
[10:27:45.732] Timer tick, asking Buildbucket for the build status
[10:27:45.866] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:45.866] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:19:0) after 1m0s
[10:28:45.922] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:19:0)
[10:28:45.922] Timer tick, asking Buildbucket for the build status
[10:28:46.102] Build 8876075389915403792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:46.102] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9041505452285565856:20:0) after 1m0s
[10:29:18.015] Received PubSub notification, asking Buildbucket for the build status
[10:29:18.825] Build 8876075389915403792: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:29:18.825] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"completed_ts": "1593512957223938",
"created_by": "project:webrtc",
"created_ts": "1593511892360479",
"id": "8876075389915403792",
"parameters_json": "{\"builder_name\": \"Win32 Release (Clang)\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://webrtc.googlesource.com/src\", \"revision\": \"4b5792cc4a19502e46e46a257945c27bf2205055\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@4b5792cc4a19502e46e46a257945c27bf2205055\", \"title\": \"4b5792cc4a19502e46e46a257945c27bf2205055\", \"url\": \"https://webrtc.googlesource.com/src/+/4b5792cc4a19502e46e46a257945c27bf2205055\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"4b5792cc4a19502e46e46a257945c27bf2205055\"}}",
"project": "webrtc",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$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}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://webrtc.googlesource.com/src\", \"revision\": \"4b5792cc4a19502e46e46a257945c27bf2205055\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@4b5792cc4a19502e46e46a257945c27bf2205055\", \"title\": \"4b5792cc4a19502e46e46a257945c27bf2205055\", \"url\": \"https://webrtc.googlesource.com/src/+/4b5792cc4a19502e46e46a257945c27bf2205055\"}]}, \"bot_id\": \"luci-webrtc-ci-win10-9-sb1h\", \"branch\": \"refs/heads/master\", \"buildername\": \"Win32 Release (Clang)\", \"buildnumber\": 19929, \"clang_revision\": \"n358615-fb1aa286-2\", \"got_revision\": \"4b5792cc4a19502e46e46a257945c27bf2205055\", \"got_revision_cp\": \"refs/heads/master@{#31588}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.webrtc\", \"path_config\": \"generic\", \"recipe\": \"webrtc/standalone\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"4b5792cc4a19502e46e46a257945c27bf2205055\", \"swarm_hashes\": {\"audio_decoder_unittests\": \"13b7a14c4fcb416b36fe32019d1fdac84cb96ce8\", \"common_audio_unittests\": \"af6d9ef7c601209e798fc8da52665e8f692edd62\", \"common_video_unittests\": \"d387b62560e7a276366d41b771c38fc70b11eea5\", \"low_bandwidth_audio_test\": \"461394a345a12ed0b2763159f6b3efe3d2181c38\", \"modules_tests\": \"5c07cc24e10c90e58837e57a90a533c0c1c5a083\", \"modules_unittests\": \"63b510e965a45f8d80b6bd29e39020784a97d92f\", \"peerconnection_unittests\": \"46bc705af207f808d62107d5a92bd4428a4955fd\", \"rtc_media_unittests\": \"b20a37954565c74d5c2f39a3973469093677accc\", \"rtc_pc_unittests\": \"d431f44f6812bfb47dba5ac47963c33c8ea83962\", \"rtc_stats_unittests\": \"37861e6f12eb418ae1003920e692ed12cb84746e\", \"rtc_unittests\": \"0d60124d6ac4921ab60075da16589e751cd4b742\", \"slow_tests\": \"434b794b8abda3deb525bc32ca054f29f4d441d6\", \"system_wrappers_unittests\": \"505f9219eb23689aa72fec12d202c891056f9b47\", \"test_support_unittests\": \"81a9376590d726e9a52a39ce075e37b81c19ab74\", \"tools_unittests\": \"a8356afca50f0c85cb96be97b99b78659060491c\", \"video_capture_tests\": \"1609ad9bc8fc5ff7c045b752c936aeb19b494fc0\", \"video_engine_tests\": \"444e00bd1276e343b776904bcc71681aa0477e58\", \"voip_unittests\": \"e650f1298ebad96c337bb01ff962b104f040d37e\", \"webrtc_nonparallel_tests\": \"baf502eb1592e50d28e6ca9926210c8aec5f1b44\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_d4cf30aa8e1095848282108f5f4488ab16aae696a6c1683094f7185c189d02b1_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"webrtc-ci\"], \"gpu\": [\"none\"], \"id\": [\"luci-webrtc-ci-win10-9-sb1h\"], \"image\": [\"chrome-win10-1703-20052900-58250aa4257\"], \"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\": [\"5218-123b763\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1593511963957018",
"status": "COMPLETED",
"status_changed_ts": "1593512957657870",
"tags": [
"build_address:luci.webrtc.ci/Win32 Release (Clang)/19929",
"builder:Win32 Release (Clang)",
"buildset:commit/git/4b5792cc4a19502e46e46a257945c27bf2205055",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/4b5792cc4a19502e46e46a257945c27bf2205055",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9041505452285565856",
"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/8876075389915403792/+/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:4d1d881a1f101d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1593512957675059",
"url": "https://ci.chromium.org/b/8876075389915403792",
"utcnow_ts": "1593512958809792"
}
}
[10:29:18.825] Invocation finished in 17m48.108820096s with status SUCCEEDED