[14:36:19.960] New invocation is queued and will start shortly
[14:36:21.869] Starting the invocation (attempt 1)
[14:36:21.913] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:36:21.913] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:36:21.913] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9056709081576374800",
"parameters_json": "{\"builder_name\":\"Linux32 Debug\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://webrtc.googlesource.com/src\",\"revision\":\"6153e15d317374a96abb0b3cffb6b6262c1a39cd\"},\"id\":\"https://webrtc.googlesource.com/src/+/refs/heads/master@6153e15d317374a96abb0b3cffb6b6262c1a39cd\",\"title\":\"6153e15d317374a96abb0b3cffb6b6262c1a39cd\",\"url\":\"https://webrtc.googlesource.com/src/+/6153e15d317374a96abb0b3cffb6b6262c1a39cd\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src\",\"revision\":\"6153e15d317374a96abb0b3cffb6b6262c1a39cd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux32 Debug",
"scheduler_invocation_id:9056709081576374800",
"scheduler_job_id:webrtc/Linux32 Debug",
"user_agent:luci-scheduler",
"buildset:commit/git/6153e15d317374a96abb0b3cffb6b6262c1a39cd",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/6153e15d317374a96abb0b3cffb6b6262c1a39cd",
"gitiles_ref:refs/heads/master"
]
}
[14:36:23.460] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"created_by": "project:webrtc",
"created_ts": "1579012582261319",
"id": "8891279018501844064",
"parameters_json": "{\"builder_name\": \"Linux32 Debug\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://webrtc.googlesource.com/src\", \"revision\": \"6153e15d317374a96abb0b3cffb6b6262c1a39cd\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@6153e15d317374a96abb0b3cffb6b6262c1a39cd\", \"title\": \"6153e15d317374a96abb0b3cffb6b6262c1a39cd\", \"url\": \"https://webrtc.googlesource.com/src/+/6153e15d317374a96abb0b3cffb6b6262c1a39cd\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"6153e15d317374a96abb0b3cffb6b6262c1a39cd\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579012583157714",
"tags": [
"build_address:luci.webrtc.ci/Linux32 Debug/23520",
"builder:Linux32 Debug",
"buildset:commit/git/6153e15d317374a96abb0b3cffb6b6262c1a39cd",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/6153e15d317374a96abb0b3cffb6b6262c1a39cd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056709081576374800",
"scheduler_job_id:webrtc/Linux32 Debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8891279018501844064/+/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": "1579012583157950",
"url": "https://ci.chromium.org/b/8891279018501844064",
"utcnow_ts": "1579012583444791"
}
}
[14:36:23.460] Task URL: https://ci.chromium.org/b/8891279018501844064
[14:36:23.461] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:2:0) after 1m0s
[14:37:24.111] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:2:0)
[14:37:24.111] Timer tick, asking Buildbucket for the build status
[14:37:24.297] Build 8891279018501844064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:37:24.297] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:3:0) after 1m0s
[14:38:24.395] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:3:0)
[14:38:24.395] Timer tick, asking Buildbucket for the build status
[14:38:24.741] Build 8891279018501844064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:38:24.741] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:4:0) after 1m0s
[14:39:24.771] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:4:0)
[14:39:24.771] Timer tick, asking Buildbucket for the build status
[14:39:24.984] Build 8891279018501844064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:39:24.984] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:5:0) after 1m0s
[14:40:25.017] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:5:0)
[14:40:25.017] Timer tick, asking Buildbucket for the build status
[14:40:25.449] Build 8891279018501844064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:40:25.450] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:6:0) after 1m0s
[14:41:25.520] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:6:0)
[14:41:25.520] Timer tick, asking Buildbucket for the build status
[14:41:25.671] Build 8891279018501844064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:41:25.671] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:7:0) after 1m0s
[14:42:12.004] Received PubSub notification, asking Buildbucket for the build status
[14:42:12.189] Build 8891279018501844064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:25.796] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:7:0)
[14:42:25.796] Timer tick, asking Buildbucket for the build status
[14:42:25.927] Build 8891279018501844064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:25.927] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:9:0) after 1m0s
[14:43:26.017] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:9:0)
[14:43:26.017] Timer tick, asking Buildbucket for the build status
[14:43:26.211] Build 8891279018501844064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:26.211] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:10:0) after 1m0s
[14:44:26.362] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:10:0)
[14:44:26.362] Timer tick, asking Buildbucket for the build status
[14:44:26.885] Build 8891279018501844064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:26.885] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:11:0) after 1m0s
[14:45:26.988] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:11:0)
[14:45:26.988] Timer tick, asking Buildbucket for the build status
[14:45:27.259] Build 8891279018501844064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:27.260] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:12:0) after 1m0s
[14:46:27.461] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:12:0)
[14:46:27.461] Timer tick, asking Buildbucket for the build status
[14:46:28.095] Build 8891279018501844064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:28.095] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:13:0) after 1m0s
[14:47:28.186] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:13:0)
[14:47:28.186] Timer tick, asking Buildbucket for the build status
[14:47:28.417] Build 8891279018501844064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:28.417] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:14:0) after 1m0s
[14:48:28.697] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:14:0)
[14:48:28.697] Timer tick, asking Buildbucket for the build status
[14:48:29.357] Build 8891279018501844064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:29.357] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:15:0) after 1m0s
[14:49:29.783] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:15:0)
[14:49:29.783] Timer tick, asking Buildbucket for the build status
[14:49:30.168] Build 8891279018501844064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:30.168] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:16:0) after 1m0s
[14:50:30.337] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:16:0)
[14:50:30.337] Timer tick, asking Buildbucket for the build status
[14:50:30.944] Build 8891279018501844064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:30.944] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:17:0) after 1m0s
[14:51:31.079] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:17:0)
[14:51:31.079] Timer tick, asking Buildbucket for the build status
[14:51:31.280] Build 8891279018501844064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:31.280] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:18:0) after 1m0s
[14:52:31.358] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:18:0)
[14:52:31.358] Timer tick, asking Buildbucket for the build status
[14:52:31.914] Build 8891279018501844064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:31.914] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9056709081576374800:19:0) after 1m0s
[14:52:59.696] Received PubSub notification, asking Buildbucket for the build status
[14:52:59.815] Build 8891279018501844064: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:52:59.815] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"completed_ts": "1579013577962997",
"created_by": "project:webrtc",
"created_ts": "1579012582261319",
"id": "8891279018501844064",
"parameters_json": "{\"builder_name\": \"Linux32 Debug\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://webrtc.googlesource.com/src\", \"revision\": \"6153e15d317374a96abb0b3cffb6b6262c1a39cd\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@6153e15d317374a96abb0b3cffb6b6262c1a39cd\", \"title\": \"6153e15d317374a96abb0b3cffb6b6262c1a39cd\", \"url\": \"https://webrtc.googlesource.com/src/+/6153e15d317374a96abb0b3cffb6b6262c1a39cd\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"6153e15d317374a96abb0b3cffb6b6262c1a39cd\"}}",
"project": "webrtc",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/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\": \"6153e15d317374a96abb0b3cffb6b6262c1a39cd\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@6153e15d317374a96abb0b3cffb6b6262c1a39cd\", \"title\": \"6153e15d317374a96abb0b3cffb6b6262c1a39cd\", \"url\": \"https://webrtc.googlesource.com/src/+/6153e15d317374a96abb0b3cffb6b6262c1a39cd\"}]}, \"bot_id\": \"luci-webrtc-ci-xenial-7-25ij\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux32 Debug\", \"buildnumber\": 23520, \"got_revision\": \"6153e15d317374a96abb0b3cffb6b6262c1a39cd\", \"got_revision_cp\": \"refs/heads/master@{#30250}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.webrtc\", \"path_config\": \"generic\", \"recipe\": \"webrtc/standalone\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"6153e15d317374a96abb0b3cffb6b6262c1a39cd\", \"swarm_hashes\": {\"audio_decoder_unittests\": \"4817d255f1b41bacad12b1b027eaa7ef5fb176f9\", \"common_audio_unittests\": \"1777836984bb11f37807ad448b0c8765eab6a4d2\", \"common_video_unittests\": \"9cadedae75ad018895fe5a53f7030ca667174e3a\", \"low_bandwidth_audio_test\": \"4bc99fc95e0c4b021b07855c2f9bb8961017c33c\", \"modules_tests\": \"43335010b9ca2d6ea7525c3d3e1509431ae4913d\", \"modules_unittests\": \"00f11d7b321f910af9a3f0228ee0e92671b96bb0\", \"peerconnection_unittests\": \"ecae2f139eeed8fa608fabe3b8d382b09aec5276\", \"rtc_media_unittests\": \"45945dd0168587bade1d737109a12a5ce1ed5233\", \"rtc_pc_unittests\": \"c19c9b39f2dd09430462c260f45dbb4bea62a357\", \"rtc_stats_unittests\": \"6a76e20f9cd9b1400c1ab0d4b72837b97bfff767\", \"rtc_unittests\": \"05c0ce9a2c8895a5f3dffde0539368609af36a9f\", \"slow_tests\": \"bd1f29d70f7979666c378dc10e99a43862a89a07\", \"system_wrappers_unittests\": \"3ef939d796ccef5eee6914c542db4232a151359d\", \"test_support_unittests\": \"287fad4b11564d573a592b3d90644da7ecfe0d2b\", \"tools_unittests\": \"b1c1a25e7dada638c6e400070b0aa8cf1c5b1427\", \"video_engine_tests\": \"291d39efb33dfdb6dbb052f93db968f6574e33fe\", \"webrtc_nonparallel_tests\": \"92c345a7cbda358e53d3a74858a072b7f6b25538\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_0cd671f6f485fc3166b8a13bcda7c7c85f534360e51d98839e042fae0638f2a6_v2\", \"builder_cd4d33579b11e7a69084e8598c51fb3312c4dc0339897950696e4b0a59553346_v2\", \"builder_d5629c2cf2c35ad595411a5dcfd59fde2daf263f5d0c2761858cad08923fcc47_v2\", \"builder_ee30cfe615ad79242c90cf868fc3e32771e3342e63241239745f710a5c60d840_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"webrtc-ci\"], \"gpu\": [\"none\"], \"id\": [\"luci-webrtc-ci-xenial-7-25ij\"], \"image\": [\"chrome-xenial-19121500-b03549ec744\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.webrtc.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4760-d9a0e6d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1579012931189430",
"status": "COMPLETED",
"status_changed_ts": "1579013578343680",
"tags": [
"build_address:luci.webrtc.ci/Linux32 Debug/23520",
"builder:Linux32 Debug",
"buildset:commit/git/6153e15d317374a96abb0b3cffb6b6262c1a39cd",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/6153e15d317374a96abb0b3cffb6b6262c1a39cd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056709081576374800",
"scheduler_job_id:webrtc/Linux32 Debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8891279018501844064/+/annotations",
"swarming_tag:luci_project:webrtc",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:webrtc/standalone",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:49bd4dd6677bbc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1579013578467022",
"url": "https://ci.chromium.org/b/8891279018501844064",
"utcnow_ts": "1579013579804232"
}
}
[14:52:59.815] Invocation finished in 16m39.870176731s with status SUCCEEDED