[21:51:00.721] New invocation is queued and will start shortly
[21:51:02.507] Starting the invocation (attempt 1)
[21:51:02.546] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:51:02.546] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:51:02.546] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9021167723087173104",
"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\":\"51f8e09540b1816236ceb1eaa540a7adb019b393\"},\"id\":\"https://webrtc.googlesource.com/src/+/refs/heads/master@51f8e09540b1816236ceb1eaa540a7adb019b393\",\"title\":\"51f8e09540b1816236ceb1eaa540a7adb019b393\",\"url\":\"https://webrtc.googlesource.com/src/+/51f8e09540b1816236ceb1eaa540a7adb019b393\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src\",\"revision\":\"51f8e09540b1816236ceb1eaa540a7adb019b393\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux32 Debug",
"scheduler_invocation_id:9021167723087173104",
"scheduler_job_id:webrtc/Linux32 Debug",
"user_agent:luci-scheduler",
"buildset:commit/git/51f8e09540b1816236ceb1eaa540a7adb019b393",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/51f8e09540b1816236ceb1eaa540a7adb019b393",
"gitiles_ref:refs/heads/master"
]
}
[21:51:05.342] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"created_by": "project:webrtc",
"created_ts": "1612907463050683",
"id": "8855737659982949936",
"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\": \"51f8e09540b1816236ceb1eaa540a7adb019b393\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@51f8e09540b1816236ceb1eaa540a7adb019b393\", \"title\": \"51f8e09540b1816236ceb1eaa540a7adb019b393\", \"url\": \"https://webrtc.googlesource.com/src/+/51f8e09540b1816236ceb1eaa540a7adb019b393\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"51f8e09540b1816236ceb1eaa540a7adb019b393\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612907464601277",
"tags": [
"build_address:luci.webrtc.ci/Linux32 Debug/26388",
"builder:Linux32 Debug",
"buildset:commit/git/51f8e09540b1816236ceb1eaa540a7adb019b393",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/51f8e09540b1816236ceb1eaa540a7adb019b393",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021167723087173104",
"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/8855737659982949936/+/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": "1612907464601420",
"url": "https://ci.chromium.org/b/8855737659982949936",
"utcnow_ts": "1612907465309684"
}
}
[21:51:05.342] Task URL: https://ci.chromium.org/b/8855737659982949936
[21:51:05.342] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:2:0) after 1m0s
[21:52:05.396] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:2:0)
[21:52:05.396] Timer tick, asking Buildbucket for the build status
[21:52:05.487] Build 8855737659982949936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:05.487] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:3:0) after 1m0s
[21:53:05.520] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:3:0)
[21:53:05.520] Timer tick, asking Buildbucket for the build status
[21:53:05.837] Build 8855737659982949936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:05.837] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:4:0) after 1m0s
[21:54:05.860] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:4:0)
[21:54:05.860] Timer tick, asking Buildbucket for the build status
[21:54:05.937] Build 8855737659982949936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:05.937] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:5:0) after 1m0s
[21:55:06.017] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:5:0)
[21:55:06.018] Timer tick, asking Buildbucket for the build status
[21:55:06.128] Build 8855737659982949936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:06.128] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:6:0) after 1m0s
[21:56:06.230] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:6:0)
[21:56:06.230] Timer tick, asking Buildbucket for the build status
[21:56:06.772] Build 8855737659982949936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:06.772] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:7:0) after 1m0s
[21:57:06.883] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:7:0)
[21:57:06.883] Timer tick, asking Buildbucket for the build status
[21:57:08.643] Build 8855737659982949936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:08.643] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:8:0) after 1m0s
[21:57:49.134] Received PubSub notification, asking Buildbucket for the build status
[21:57:49.223] Build 8855737659982949936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:08.735] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:8:0)
[21:58:08.735] Timer tick, asking Buildbucket for the build status
[21:58:09.071] Build 8855737659982949936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:09.071] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:10:0) after 1m0s
[21:59:09.062] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:10:0)
[21:59:09.062] Timer tick, asking Buildbucket for the build status
[21:59:09.187] Build 8855737659982949936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:09.187] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:11:0) after 1m0s
[22:00:09.283] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:11:0)
[22:00:09.283] Timer tick, asking Buildbucket for the build status
[22:00:09.494] Build 8855737659982949936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:09.494] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:12:0) after 1m0s
[22:01:10.079] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:12:0)
[22:01:10.079] Timer tick, asking Buildbucket for the build status
[22:01:10.560] Build 8855737659982949936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:10.560] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:13:0) after 1m0s
[22:02:11.355] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:13:0)
[22:02:11.355] Timer tick, asking Buildbucket for the build status
[22:02:11.655] Build 8855737659982949936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:11.656] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:14:0) after 1m0s
[22:03:12.294] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:14:0)
[22:03:12.294] Timer tick, asking Buildbucket for the build status
[22:03:12.383] Build 8855737659982949936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:12.383] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:15:0) after 1m0s
[22:04:12.572] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:15:0)
[22:04:12.572] Timer tick, asking Buildbucket for the build status
[22:04:12.709] Build 8855737659982949936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:12.709] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:16:0) after 1m0s
[22:05:12.872] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:16:0)
[22:05:12.872] Timer tick, asking Buildbucket for the build status
[22:05:14.172] Build 8855737659982949936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:14.172] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:17:0) after 1m0s
[22:06:14.215] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:17:0)
[22:06:14.215] Timer tick, asking Buildbucket for the build status
[22:06:15.375] Build 8855737659982949936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:15.375] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:18:0) after 1m0s
[22:07:15.500] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:18:0)
[22:07:15.500] Timer tick, asking Buildbucket for the build status
[22:07:15.655] Build 8855737659982949936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:15.655] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:19:0) after 1m0s
[22:08:15.779] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:19:0)
[22:08:15.779] Timer tick, asking Buildbucket for the build status
[22:08:16.114] Build 8855737659982949936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:16.114] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:20:0) after 1m0s
[22:09:16.186] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:20:0)
[22:09:16.186] Timer tick, asking Buildbucket for the build status
[22:09:16.379] Build 8855737659982949936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:16.379] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:21:0) after 1m0s
[22:10:16.419] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:21:0)
[22:10:16.419] Timer tick, asking Buildbucket for the build status
[22:10:16.593] Build 8855737659982949936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:16.593] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:22:0) after 1m0s
[22:11:16.663] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:22:0)
[22:11:16.663] Timer tick, asking Buildbucket for the build status
[22:11:17.144] Build 8855737659982949936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:17.144] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9021167723087173104:23:0) after 1m0s
[22:11:34.101] Received PubSub notification, asking Buildbucket for the build status
[22:11:34.192] Build 8855737659982949936: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:11:34.192] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"completed_ts": "1612908691523473",
"created_by": "project:webrtc",
"created_ts": "1612907463050683",
"id": "8855737659982949936",
"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\": \"51f8e09540b1816236ceb1eaa540a7adb019b393\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@51f8e09540b1816236ceb1eaa540a7adb019b393\", \"title\": \"51f8e09540b1816236ceb1eaa540a7adb019b393\", \"url\": \"https://webrtc.googlesource.com/src/+/51f8e09540b1816236ceb1eaa540a7adb019b393\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"51f8e09540b1816236ceb1eaa540a7adb019b393\"}}",
"project": "webrtc",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$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\": \"51f8e09540b1816236ceb1eaa540a7adb019b393\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@51f8e09540b1816236ceb1eaa540a7adb019b393\", \"title\": \"51f8e09540b1816236ceb1eaa540a7adb019b393\", \"url\": \"https://webrtc.googlesource.com/src/+/51f8e09540b1816236ceb1eaa540a7adb019b393\"}]}, \"bot_id\": \"luci-webrtc-ci-xenial-1-yeam\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.webrtc\", \"buildername\": \"Linux32 Debug\", \"buildnumber\": 26388, \"got_revision\": \"51f8e09540b1816236ceb1eaa540a7adb019b393\", \"got_revision_cp\": \"refs/heads/master@{#33210}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"webrtc/standalone\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"51f8e09540b1816236ceb1eaa540a7adb019b393\", \"swarm_hashes\": {\"audio_decoder_unittests\": \"0a81dba7971642d2368ed0e7815e2d3328a1b743266ba42cf800c0f9b64af218/495\", \"common_audio_unittests\": \"84bbc0540a4b548c397eeb82c4abbb42712cd2ae562fd582cd271dadb039cfc8/412\", \"common_video_unittests\": \"35075c8d9e74ff84e7b9c1c725130aef2e5a962ba3d707a437c9da3100f0a8ed/495\", \"low_bandwidth_audio_test\": \"1e645423cb68bc6e0908354474dfb9595527274506bc1b8e225233773f9cb4e9/495\", \"modules_tests\": \"3e38bd7755e2f160f4dcf6dc260e2d2d0c0751f06563d6c9a3c2e7963f33daa4/496\", \"modules_unittests\": \"fa75542433cf51354029cfa77ad8c8fe14e487c704a1f8c017c736909ec0cf32/497\", \"peerconnection_unittests\": \"5464ca3adb4c8023458ff4136066254be6d234132abcc3dfddecdb955b76e62a/496\", \"rtc_media_unittests\": \"103351124a5ea5f86f37334aed72d7c71d4622147fa9672cfbb2a547210f55bb/495\", \"rtc_pc_unittests\": \"5b669c766cb9f20cbafa41ad67aa9cf037792c03651d3cdfd0403c5923cabdd8/412\", \"rtc_stats_unittests\": \"a3a55293aa245af500d5672d1a0cfdfbd39554228ed54cdd5194e44a1c387ac5/412\", \"rtc_unittests\": \"b9270111e5b4649076bdcbba9867ec19fd89db1a1f454b4ac57c84f8931845d1/412\", \"slow_tests\": \"9a36ebda5f6def6ad4d9286a1d6edf1408fa5a136faa8dfeda8150f144037ab8/412\", \"system_wrappers_unittests\": \"59181a394b3c9f9915cb65d638ff9e0d051503ce9ecf5c060970792e7be27791/412\", \"test_support_unittests\": \"c4b0906b0369e7ce05eba8c8b973f6d3d98d4488c9dbec9cfdbcff15c3edc882/496\", \"tools_unittests\": \"e69d88f43e3af2f01bcef4902f710624d2ec6bd56d3c729c621a8d91cf1982f8/496\", \"video_engine_tests\": \"b6f210ba10ae55f36676eddfa1b00ace379c1ec9f804e789e23e56dcbde66b81/496\", \"voip_unittests\": \"9a38da36268e346901757196d2cb86706233473c55185da6ae1c61413968bc75/412\", \"webrtc_nonparallel_tests\": \"f41d808635e04f81c81e365c70978b31d964d4fd1dbe0313fd251f1b75bfa8de/412\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_455888f2f945b3280d12644ca1ffba1d78ccc60e6253093e09b5ed6f38897bd7_v2\", \"builder_a9e857c6f45aeca8cc1388c7c30c9ddfcf1f8acd0c8e85588eec9b6e05b9c136_v2\", \"builder_ee80cf8bba7c81fee9e980afd619ea0acf20624840b3b5e7cf3f2ef0d6b70dc4_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"webrtc-ci\"], \"gpu\": [\"none\"], \"id\": [\"luci-webrtc-ci-xenial-1-yeam\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.webrtc.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5685-1877293\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612907868640578",
"status": "COMPLETED",
"status_changed_ts": "1612908692972711",
"tags": [
"build_address:luci.webrtc.ci/Linux32 Debug/26388",
"builder:Linux32 Debug",
"buildset:commit/git/51f8e09540b1816236ceb1eaa540a7adb019b393",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/51f8e09540b1816236ceb1eaa540a7adb019b393",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021167723087173104",
"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/8855737659982949936/+/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:51a19851aecb2310",
"user_agent:luci-scheduler"
],
"updated_ts": "1612908693278747",
"url": "https://ci.chromium.org/b/8855737659982949936",
"utcnow_ts": "1612908694183752"
}
}
[22:11:34.192] Invocation finished in 20m33.486597478s with status SUCCEEDED