[12:21:50.585] New invocation is queued and will start shortly
[12:21:51.689] Starting the invocation (attempt 1)
[12:21:51.762] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:21:51.762] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:21:51.762] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9064146494176138416",
"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\":\"a9229043e357edfa882e80a226d4653079ee730d\"},\"id\":\"https://webrtc.googlesource.com/src/+/refs/heads/master@a9229043e357edfa882e80a226d4653079ee730d\",\"title\":\"a9229043e357edfa882e80a226d4653079ee730d\",\"url\":\"https://webrtc.googlesource.com/src/+/a9229043e357edfa882e80a226d4653079ee730d\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src\",\"revision\":\"a9229043e357edfa882e80a226d4653079ee730d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Win32 Release (Clang)",
"scheduler_invocation_id:9064146494176138416",
"scheduler_job_id:webrtc/Win32 Release (Clang)",
"user_agent:luci-scheduler",
"buildset:commit/git/a9229043e357edfa882e80a226d4653079ee730d",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/a9229043e357edfa882e80a226d4653079ee730d",
"gitiles_ref:refs/heads/master"
]
}
[12:21:52.868] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"created_by": "project:webrtc",
"created_ts": "1571919711880573",
"id": "8898716432153470880",
"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\": \"a9229043e357edfa882e80a226d4653079ee730d\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@a9229043e357edfa882e80a226d4653079ee730d\", \"title\": \"a9229043e357edfa882e80a226d4653079ee730d\", \"url\": \"https://webrtc.googlesource.com/src/+/a9229043e357edfa882e80a226d4653079ee730d\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"a9229043e357edfa882e80a226d4653079ee730d\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1571919712564720",
"tags": [
"build_address:luci.webrtc.ci/Win32 Release (Clang)/17988",
"builder:Win32 Release (Clang)",
"buildset:commit/git/a9229043e357edfa882e80a226d4653079ee730d",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/a9229043e357edfa882e80a226d4653079ee730d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9064146494176138416",
"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/8898716432153470880/+/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": "1571919712564835",
"url": "https://ci.chromium.org/b/8898716432153470880",
"utcnow_ts": "1571919712848183"
}
}
[12:21:52.868] Task URL: https://ci.chromium.org/b/8898716432153470880
[12:21:52.868] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:2:0) after 1m0s
[12:22:52.944] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:2:0)
[12:22:52.944] Timer tick, asking Buildbucket for the build status
[12:22:53.188] Build 8898716432153470880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:22:53.188] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:3:0) after 1m0s
[12:23:53.539] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:3:0)
[12:23:53.539] Timer tick, asking Buildbucket for the build status
[12:23:53.640] Build 8898716432153470880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:23:53.640] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:4:0) after 1m0s
[12:24:53.902] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:4:0)
[12:24:53.902] Timer tick, asking Buildbucket for the build status
[12:24:54.026] Build 8898716432153470880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:24:54.026] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:5:0) after 1m0s
[12:25:54.258] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:5:0)
[12:25:54.258] Timer tick, asking Buildbucket for the build status
[12:25:54.645] Build 8898716432153470880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:25:54.645] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:6:0) after 1m0s
[12:26:54.657] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:6:0)
[12:26:54.657] Timer tick, asking Buildbucket for the build status
[12:26:57.533] Build 8898716432153470880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:26:57.533] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:7:0) after 1m0s
[12:27:20.006] Received PubSub notification, asking Buildbucket for the build status
[12:27:20.168] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:57.672] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:7:0)
[12:27:57.672] Timer tick, asking Buildbucket for the build status
[12:27:57.900] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:57.900] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:9:0) after 1m0s
[12:28:57.925] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:9:0)
[12:28:57.925] Timer tick, asking Buildbucket for the build status
[12:28:58.226] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:58.226] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:10:0) after 1m0s
[12:29:58.296] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:10:0)
[12:29:58.296] Timer tick, asking Buildbucket for the build status
[12:29:58.553] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:58.553] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:11:0) after 1m0s
[12:30:58.573] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:11:0)
[12:30:58.573] Timer tick, asking Buildbucket for the build status
[12:30:58.823] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:58.823] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:12:0) after 1m0s
[12:31:58.919] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:12:0)
[12:31:58.919] Timer tick, asking Buildbucket for the build status
[12:31:59.067] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:59.067] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:13:0) after 1m0s
[12:32:59.125] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:13:0)
[12:32:59.125] Timer tick, asking Buildbucket for the build status
[12:32:59.213] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:59.213] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:14:0) after 1m0s
[12:33:59.268] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:14:0)
[12:33:59.268] Timer tick, asking Buildbucket for the build status
[12:34:02.025] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:02.025] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:15:0) after 1m0s
[12:35:02.072] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:15:0)
[12:35:02.072] Timer tick, asking Buildbucket for the build status
[12:35:02.283] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:02.283] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:16:0) after 1m0s
[12:36:02.403] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:16:0)
[12:36:02.403] Timer tick, asking Buildbucket for the build status
[12:36:02.570] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:02.570] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:17:0) after 1m0s
[12:37:02.669] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:17:0)
[12:37:02.669] Timer tick, asking Buildbucket for the build status
[12:37:02.834] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:02.834] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:18:0) after 1m0s
[12:38:02.915] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:18:0)
[12:38:02.915] Timer tick, asking Buildbucket for the build status
[12:38:03.024] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:03.025] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:19:0) after 1m0s
[12:39:03.079] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:19:0)
[12:39:03.079] Timer tick, asking Buildbucket for the build status
[12:39:03.207] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:03.207] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:20:0) after 1m0s
[12:40:03.239] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:20:0)
[12:40:03.239] Timer tick, asking Buildbucket for the build status
[12:40:03.697] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:03.697] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:21:0) after 1m0s
[12:41:03.735] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:21:0)
[12:41:03.735] Timer tick, asking Buildbucket for the build status
[12:41:04.177] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:04.177] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:22:0) after 1m0s
[12:42:04.241] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:22:0)
[12:42:04.241] Timer tick, asking Buildbucket for the build status
[12:42:04.776] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:04.776] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:23:0) after 1m0s
[12:43:04.872] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:23:0)
[12:43:04.872] Timer tick, asking Buildbucket for the build status
[12:43:05.101] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:05.101] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:24:0) after 1m0s
[12:44:05.149] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:24:0)
[12:44:05.149] Timer tick, asking Buildbucket for the build status
[12:44:05.498] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:05.498] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:25:0) after 1m0s
[12:45:05.559] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:25:0)
[12:45:05.560] Timer tick, asking Buildbucket for the build status
[12:45:05.758] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:05.758] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:26:0) after 1m0s
[12:46:05.810] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:26:0)
[12:46:05.810] Timer tick, asking Buildbucket for the build status
[12:46:06.093] Build 8898716432153470880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:06.093] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9064146494176138416:27:0) after 1m0s
[12:46:48.874] Received PubSub notification, asking Buildbucket for the build status
[12:46:49.368] Build 8898716432153470880: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:46:49.368] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"completed_ts": "1571921207908211",
"created_by": "project:webrtc",
"created_ts": "1571919711880573",
"id": "8898716432153470880",
"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\": \"a9229043e357edfa882e80a226d4653079ee730d\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@a9229043e357edfa882e80a226d4653079ee730d\", \"title\": \"a9229043e357edfa882e80a226d4653079ee730d\", \"url\": \"https://webrtc.googlesource.com/src/+/a9229043e357edfa882e80a226d4653079ee730d\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"a9229043e357edfa882e80a226d4653079ee730d\"}}",
"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}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://webrtc.googlesource.com/src\", \"revision\": \"a9229043e357edfa882e80a226d4653079ee730d\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@a9229043e357edfa882e80a226d4653079ee730d\", \"title\": \"a9229043e357edfa882e80a226d4653079ee730d\", \"url\": \"https://webrtc.googlesource.com/src/+/a9229043e357edfa882e80a226d4653079ee730d\"}]}, \"bot_id\": \"luci-webrtc-ci-win10-10-fpph\", \"branch\": \"refs/heads/master\", \"buildername\": \"Win32 Release (Clang)\", \"buildnumber\": 17988, \"got_clang_revision\": \"373424-64a362e7-1\", \"got_revision\": \"a9229043e357edfa882e80a226d4653079ee730d\", \"got_revision_cp\": \"refs/heads/master@{#29601}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.webrtc\", \"path_config\": \"generic\", \"recipe\": \"webrtc/standalone\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"a9229043e357edfa882e80a226d4653079ee730d\", \"swarm_hashes\": {\"audio_decoder_unittests\": \"9ecdcd89a54eddc7c58a06c7c3ff020ad2f84b81\", \"common_audio_unittests\": \"f9abade07db83b3588dbab91dab3310ebed6fe0e\", \"common_video_unittests\": \"0fe211756ce4db24eac5356d48998e720ac40ca5\", \"low_bandwidth_audio_test\": \"9b4bccb71d50cf88a485b244708775ff58122404\", \"modules_tests\": \"35d38834370a7a5675751ea2fcca4f0553bd4f33\", \"modules_unittests\": \"adb9db2b59291cb689220b241d330e470eb56c54\", \"peerconnection_unittests\": \"ce24fee90fb486cdb5398c382734440051994ef3\", \"rtc_media_unittests\": \"c715a913b7085c689aa8d17f7281b366394bce89\", \"rtc_pc_unittests\": \"ddc2a3e5ef25c4f8d7683c3cf089a59b892fea29\", \"rtc_stats_unittests\": \"c018d80d187a636633d8e765e4c115f2cff0017f\", \"rtc_unittests\": \"f71fbad8a7412a67708c6f301b69f4f8b183227d\", \"slow_tests\": \"3a6bb14b6d9aa5ba5bce1297ae81e2d669a9d0a6\", \"system_wrappers_unittests\": \"b5e5096c359e5fb051e11d27b9024b3c0a854391\", \"test_support_unittests\": \"802d818a7d7360e021bd4f9f6960f2a9f65a04df\", \"tools_unittests\": \"d8c3db08053130b90c716d641ceaa9583161cfd0\", \"video_capture_tests\": \"585ff7057772d904d2919e7b14f17aa18abf2e8f\", \"video_engine_tests\": \"b8ae09197c748fe7acc477d86ecc4bfff8b41c0d\", \"webrtc_nonparallel_tests\": \"673fabb9dd43d8e75ff96c74d579e1b6bad79799\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_bd96998d655bd1665c3e1d055a9a19135b66e32f4ab0e0476522ee418bec2b7d_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-webrtc-ci-win10-10-fpph\"], \"image\": [\"chrome-win10-1703-19102200-47150345821\"], \"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\": [\"4549-8dc3ae7\"], \"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": "1571920038937932",
"status": "COMPLETED",
"status_changed_ts": "1571921208239626",
"tags": [
"build_address:luci.webrtc.ci/Win32 Release (Clang)/17988",
"builder:Win32 Release (Clang)",
"buildset:commit/git/a9229043e357edfa882e80a226d4653079ee730d",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/a9229043e357edfa882e80a226d4653079ee730d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9064146494176138416",
"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/8898716432153470880/+/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:4816892b54cdd010",
"user_agent:luci-scheduler"
],
"updated_ts": "1571921208249689",
"url": "https://ci.chromium.org/b/8898716432153470880",
"utcnow_ts": "1571921209354204"
}
}
[12:46:49.369] Invocation finished in 24m58.800324807s with status SUCCEEDED