[14:21:12.245] New invocation is queued and will start shortly
[14:21:13.356] Starting the invocation (attempt 1)
[14:21:13.395] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:21:13.395] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:21:13.395] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9043211085396398880",
"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\":\"4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\"},\"id\":\"https://webrtc.googlesource.com/src/+/refs/heads/master@4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\",\"title\":\"4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\",\"url\":\"https://webrtc.googlesource.com/src/+/4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src\",\"revision\":\"4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Win32 Release (Clang)",
"scheduler_invocation_id:9043211085396398880",
"scheduler_job_id:webrtc/Win32 Release (Clang)",
"user_agent:luci-scheduler",
"buildset:commit/git/4c1e7cc19bc52f2724ddd419234bf7ba12d2c517",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/4c1e7cc19bc52f2724ddd419234bf7ba12d2c517",
"gitiles_ref:refs/heads/master"
]
}
[14:21:15.232] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"created_by": "project:webrtc",
"created_ts": "1591885273643595",
"id": "8877781023262308000",
"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\": \"4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\", \"title\": \"4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\", \"url\": \"https://webrtc.googlesource.com/src/+/4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1591885274785194",
"tags": [
"build_address:luci.webrtc.ci/Win32 Release (Clang)/19845",
"builder:Win32 Release (Clang)",
"buildset:commit/git/4c1e7cc19bc52f2724ddd419234bf7ba12d2c517",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/4c1e7cc19bc52f2724ddd419234bf7ba12d2c517",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9043211085396398880",
"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/8877781023262308000/+/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": "1591885274785484",
"url": "https://ci.chromium.org/b/8877781023262308000",
"utcnow_ts": "1591885275209021"
}
}
[14:21:15.232] Task URL: https://ci.chromium.org/b/8877781023262308000
[14:21:15.232] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:2:0) after 1m0s
[14:22:15.335] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:2:0)
[14:22:15.335] Timer tick, asking Buildbucket for the build status
[14:22:15.658] Build 8877781023262308000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:22:15.658] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:3:0) after 1m0s
[14:23:15.726] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:3:0)
[14:23:15.726] Timer tick, asking Buildbucket for the build status
[14:23:15.848] Build 8877781023262308000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:23:15.848] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:4:0) after 1m0s
[14:24:15.919] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:4:0)
[14:24:15.920] Timer tick, asking Buildbucket for the build status
[14:24:16.070] Build 8877781023262308000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:24:16.070] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:5:0) after 1m0s
[14:25:16.113] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:5:0)
[14:25:16.113] Timer tick, asking Buildbucket for the build status
[14:25:16.236] Build 8877781023262308000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:25:16.236] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:6:0) after 1m0s
[14:26:16.322] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:6:0)
[14:26:16.322] Timer tick, asking Buildbucket for the build status
[14:26:16.503] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:16.503] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:7:0) after 1m0s
[14:26:16.645] Received PubSub notification, asking Buildbucket for the build status
[14:26:16.807] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:16.644] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:7:0)
[14:27:16.644] Timer tick, asking Buildbucket for the build status
[14:27:16.829] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:16.829] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:9:0) after 1m0s
[14:28:16.866] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:9:0)
[14:28:16.866] Timer tick, asking Buildbucket for the build status
[14:28:17.003] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:17.003] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:10:0) after 1m0s
[14:29:17.026] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:10:0)
[14:29:17.026] Timer tick, asking Buildbucket for the build status
[14:29:17.186] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:17.186] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:11:0) after 1m0s
[14:30:17.314] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:11:0)
[14:30:17.314] Timer tick, asking Buildbucket for the build status
[14:30:17.454] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:17.454] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:12:0) after 1m0s
[14:31:17.573] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:12:0)
[14:31:17.573] Timer tick, asking Buildbucket for the build status
[14:31:17.903] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:17.903] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:13:0) after 1m0s
[14:32:17.943] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:13:0)
[14:32:17.943] Timer tick, asking Buildbucket for the build status
[14:32:18.154] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:18.154] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:14:0) after 1m0s
[14:33:18.219] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:14:0)
[14:33:18.219] Timer tick, asking Buildbucket for the build status
[14:33:18.358] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:18.358] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:15:0) after 1m0s
[14:34:18.413] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:15:0)
[14:34:18.413] Timer tick, asking Buildbucket for the build status
[14:34:18.533] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:18.533] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:16:0) after 1m0s
[14:35:18.605] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:16:0)
[14:35:18.605] Timer tick, asking Buildbucket for the build status
[14:35:18.749] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:18.749] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:17:0) after 1m0s
[14:36:18.923] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:17:0)
[14:36:18.923] Timer tick, asking Buildbucket for the build status
[14:36:19.111] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:19.111] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:18:0) after 1m0s
[14:37:19.169] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:18:0)
[14:37:19.169] Timer tick, asking Buildbucket for the build status
[14:37:19.313] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:19.313] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:19:0) after 1m0s
[14:38:19.370] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:19:0)
[14:38:19.370] Timer tick, asking Buildbucket for the build status
[14:38:19.499] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:19.499] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:20:0) after 1m0s
[14:39:19.573] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:20:0)
[14:39:19.573] Timer tick, asking Buildbucket for the build status
[14:39:19.703] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:19.703] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:21:0) after 1m0s
[14:40:20.068] Handling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:21:0)
[14:40:20.068] Timer tick, asking Buildbucket for the build status
[14:40:20.202] Build 8877781023262308000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:20.202] Scheduling timer "check-buildbucket-build-status" (webrtc/Win32 Release (Clang):9043211085396398880:22:0) after 1m0s
[14:41:02.476] Received PubSub notification, asking Buildbucket for the build status
[14:41:02.639] Build 8877781023262308000: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:41:02.639] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"completed_ts": "1591886461579289",
"created_by": "project:webrtc",
"created_ts": "1591885273643595",
"id": "8877781023262308000",
"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\": \"4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\", \"title\": \"4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\", \"url\": \"https://webrtc.googlesource.com/src/+/4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\"}}",
"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\": \"4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\", \"title\": \"4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\", \"url\": \"https://webrtc.googlesource.com/src/+/4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\"}]}, \"bot_id\": \"luci-webrtc-ci-win10-0-ki0l\", \"branch\": \"refs/heads/master\", \"buildername\": \"Win32 Release (Clang)\", \"buildnumber\": 19845, \"clang_revision\": \"n356235-f7f1abdb-1\", \"got_revision\": \"4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\", \"got_revision_cp\": \"refs/heads/master@{#31505}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.webrtc\", \"path_config\": \"generic\", \"recipe\": \"webrtc/standalone\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"4c1e7cc19bc52f2724ddd419234bf7ba12d2c517\", \"swarm_hashes\": {\"audio_decoder_unittests\": \"14c1fd3e165c6ff2f05db152ef4880f5061abcfd\", \"common_audio_unittests\": \"6c2e4b42a168bb98dbc31ecf6e1820ba34dd7a95\", \"common_video_unittests\": \"9080ac848497e0517b85ea89dca2270425add4e6\", \"low_bandwidth_audio_test\": \"23c23e0381da80eff7bb3ec2c7ef677b9de87914\", \"modules_tests\": \"56f397be107f5898b30a2583d1f19aec16eaea96\", \"modules_unittests\": \"17f945849debf338d43b2fa250c074203895b397\", \"peerconnection_unittests\": \"0c8901a7fd667f5e837dac26e7027a38c47a40c2\", \"rtc_media_unittests\": \"95f9794dc81dcd3afc56cc3ef6abc64f5d4bc315\", \"rtc_pc_unittests\": \"26ac0a398cf166c25093961e6eb3a018c2bf615f\", \"rtc_stats_unittests\": \"c760e6ed2f14ae2121ad632fa52b2e2450ca22b6\", \"rtc_unittests\": \"5a9d91d736f88c5e62a96c7c5d51bb7c95e5fef5\", \"slow_tests\": \"877f629971ba503cfd6f48aebd433363cd6a0281\", \"system_wrappers_unittests\": \"75e52b80911586ff92eac3d25cb73f86c99be041\", \"test_support_unittests\": \"a015e94a20b7fbbbc094ae8083ab3a62e7518b8b\", \"tools_unittests\": \"622e64729c7c133ec32b6eac29e61f56c7687100\", \"video_capture_tests\": \"831ff89d2348b8770648511b84c6b5c1615e3b06\", \"video_engine_tests\": \"a3f7d24e4a2cd4ff1f57ed0c34bd5a74886bdf3a\", \"voip_unittests\": \"eb987c25adc2f1b9ea6668be861ac8d72123b456\", \"webrtc_nonparallel_tests\": \"e4344ed8ba5ffa543902e1e9e9d46de208790255\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_5b73e9f61d4729f3a7f02352e92ecfb5d2782cb938aae5a4346f6d7f59d354b5_v2\", \"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-0-ki0l\"], \"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\": [\"5199-07c11c0\"], \"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": "1591885574722917",
"status": "COMPLETED",
"status_changed_ts": "1591886462209719",
"tags": [
"build_address:luci.webrtc.ci/Win32 Release (Clang)/19845",
"builder:Win32 Release (Clang)",
"buildset:commit/git/4c1e7cc19bc52f2724ddd419234bf7ba12d2c517",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/4c1e7cc19bc52f2724ddd419234bf7ba12d2c517",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9043211085396398880",
"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/8877781023262308000/+/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:4cbc932b3dda1d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1591886462222771",
"url": "https://ci.chromium.org/b/8877781023262308000",
"utcnow_ts": "1591886462628417"
}
}
[14:41:02.639] Invocation finished in 19m50.414754627s with status SUCCEEDED