[20:15:27.806] New invocation is queued and will start shortly
[20:15:35.056] Starting the invocation (attempt 1)
[20:15:35.093] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:15:35.093] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:15:35.093] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9041558051979219792",
"parameters_json": "{\"builder_name\":\"Linux64 Debug\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://webrtc.googlesource.com/src\",\"revision\":\"67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\"},\"id\":\"https://webrtc.googlesource.com/src/+/refs/heads/master@67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\",\"title\":\"67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\",\"url\":\"https://webrtc.googlesource.com/src/+/67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src\",\"revision\":\"67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux64 Debug",
"scheduler_invocation_id:9041558051979219792",
"scheduler_job_id:webrtc/Linux64 Debug",
"user_agent:luci-scheduler",
"buildset:commit/git/67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb",
"gitiles_ref:refs/heads/master"
]
}
[20:15:37.122] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"created_by": "project:webrtc",
"created_ts": "1593461735255169",
"id": "8876127983451124128",
"parameters_json": "{\"builder_name\": \"Linux64 Debug\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://webrtc.googlesource.com/src\", \"revision\": \"67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\", \"title\": \"67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\", \"url\": \"https://webrtc.googlesource.com/src/+/67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1593461736579940",
"tags": [
"build_address:luci.webrtc.ci/Linux64 Debug/25829",
"builder:Linux64 Debug",
"buildset:commit/git/67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9041558051979219792",
"scheduler_job_id:webrtc/Linux64 Debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8876127983451124128/+/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": "1593461736580096",
"url": "https://ci.chromium.org/b/8876127983451124128",
"utcnow_ts": "1593461737093001"
}
}
[20:15:37.122] Task URL: https://ci.chromium.org/b/8876127983451124128
[20:15:37.122] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:2:0) after 1m0s
[20:16:37.181] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:2:0)
[20:16:37.181] Timer tick, asking Buildbucket for the build status
[20:16:37.338] Build 8876127983451124128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:16:37.338] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:3:0) after 1m0s
[20:17:37.493] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:3:0)
[20:17:37.493] Timer tick, asking Buildbucket for the build status
[20:17:37.862] Build 8876127983451124128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:17:37.862] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:4:0) after 1m0s
[20:18:37.926] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:4:0)
[20:18:37.926] Timer tick, asking Buildbucket for the build status
[20:18:38.036] Build 8876127983451124128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:18:38.036] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:5:0) after 1m0s
[20:19:38.277] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:5:0)
[20:19:38.277] Timer tick, asking Buildbucket for the build status
[20:19:38.684] Build 8876127983451124128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:19:38.684] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:6:0) after 1m0s
[20:20:38.744] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:6:0)
[20:20:38.744] Timer tick, asking Buildbucket for the build status
[20:20:38.933] Build 8876127983451124128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:20:38.933] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:7:0) after 1m0s
[20:21:38.993] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:7:0)
[20:21:38.993] Timer tick, asking Buildbucket for the build status
[20:21:39.139] Build 8876127983451124128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:21:39.139] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:8:0) after 1m0s
[20:22:39.225] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:8:0)
[20:22:39.225] Timer tick, asking Buildbucket for the build status
[20:22:40.780] Build 8876127983451124128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:40.780] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:9:0) after 1m0s
[20:23:40.895] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:9:0)
[20:23:40.895] Timer tick, asking Buildbucket for the build status
[20:23:41.154] Build 8876127983451124128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:41.154] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:10:0) after 1m0s
[20:24:41.262] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:10:0)
[20:24:41.262] Timer tick, asking Buildbucket for the build status
[20:24:43.652] Build 8876127983451124128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:24:43.652] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:11:0) after 1m0s
[20:24:44.771] Received PubSub notification, asking Buildbucket for the build status
[20:24:46.003] Build 8876127983451124128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:44.409] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:11:0)
[20:25:44.409] Timer tick, asking Buildbucket for the build status
[20:25:45.063] Build 8876127983451124128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:45.063] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:13:0) after 1m0s
[20:26:45.243] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:13:0)
[20:26:45.243] Timer tick, asking Buildbucket for the build status
[20:26:45.392] Build 8876127983451124128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:45.392] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:14:0) after 1m0s
[20:27:45.480] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:14:0)
[20:27:45.480] Timer tick, asking Buildbucket for the build status
[20:27:45.731] Build 8876127983451124128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:45.731] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:15:0) after 1m0s
[20:28:45.848] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:15:0)
[20:28:45.848] Timer tick, asking Buildbucket for the build status
[20:28:46.226] Build 8876127983451124128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:46.226] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:16:0) after 1m0s
[20:29:46.331] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:16:0)
[20:29:46.331] Timer tick, asking Buildbucket for the build status
[20:29:47.563] Build 8876127983451124128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:47.563] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:17:0) after 1m0s
[20:30:47.654] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:17:0)
[20:30:47.654] Timer tick, asking Buildbucket for the build status
[20:30:47.788] Build 8876127983451124128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:47.788] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:18:0) after 1m0s
[20:31:47.848] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:18:0)
[20:31:47.848] Timer tick, asking Buildbucket for the build status
[20:31:49.087] Build 8876127983451124128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:49.087] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:19:0) after 1m0s
[20:32:49.193] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:19:0)
[20:32:49.193] Timer tick, asking Buildbucket for the build status
[20:32:49.502] Build 8876127983451124128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:49.502] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:20:0) after 1m0s
[20:33:49.572] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:20:0)
[20:33:49.572] Timer tick, asking Buildbucket for the build status
[20:33:50.306] Build 8876127983451124128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:50.306] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:21:0) after 1m0s
[20:34:50.321] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:21:0)
[20:34:50.321] Timer tick, asking Buildbucket for the build status
[20:34:50.630] Build 8876127983451124128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:50.630] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:22:0) after 1m0s
[20:35:50.711] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:22:0)
[20:35:50.711] Timer tick, asking Buildbucket for the build status
[20:35:50.836] Build 8876127983451124128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:50.837] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9041558051979219792:23:0) after 1m0s
[20:36:41.743] Received PubSub notification, asking Buildbucket for the build status
[20:36:41.825] Build 8876127983451124128: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:36:41.825] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "PROD",
"completed_ts": "1593462998110286",
"created_by": "project:webrtc",
"created_ts": "1593461735255169",
"id": "8876127983451124128",
"parameters_json": "{\"builder_name\": \"Linux64 Debug\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://webrtc.googlesource.com/src\", \"revision\": \"67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\", \"title\": \"67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\", \"url\": \"https://webrtc.googlesource.com/src/+/67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\"}}",
"project": "webrtc",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$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\": \"67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\"}, \"id\": \"https://webrtc.googlesource.com/src/+/refs/heads/master@67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\", \"title\": \"67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\", \"url\": \"https://webrtc.googlesource.com/src/+/67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\"}]}, \"bot_id\": \"luci-webrtc-ci-xenial-4-j0t0\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux64 Debug\", \"buildnumber\": 25829, \"got_revision\": \"67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\", \"got_revision_cp\": \"refs/heads/master@{#31584}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.webrtc\", \"path_config\": \"generic\", \"recipe\": \"webrtc/standalone\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb\", \"swarm_hashes\": {\"audio_decoder_unittests\": \"9b2d80962b9d2b846fd4306b2d52b5a156cd6a89\", \"common_audio_unittests\": \"fc0c857cb7637b831152404b293c2ee4d8f7bc46\", \"common_video_unittests\": \"f8624839fb099fd11cd5beae15ad16e4d4b907a1\", \"low_bandwidth_audio_test\": \"f1511d6db126f03b820aa749e1e5cb875214219b\", \"modules_tests\": \"e583bcfc959b2366d5d33c8648f9f6d876d1db1a\", \"modules_unittests\": \"cdcfbe8ab09b3521d3d25dcfceeeef4209e30125\", \"peerconnection_unittests\": \"ac268e8b0bf4a89b5d2761d8527d8630ac830e1c\", \"rtc_media_unittests\": \"7e4062042165a1feeb1aac5e4fd78a5c921e595e\", \"rtc_pc_unittests\": \"fd9d362fb5a71ed9c469495968e0f8cb46a6bb66\", \"rtc_stats_unittests\": \"6a8662ccc44db84110e69a5472dd5103a7049637\", \"rtc_unittests\": \"20fcfbc83198428f08819fdb5f8b55e9d99bb2e2\", \"slow_tests\": \"44d30b4ee8c749179284972b316df17ca7920afb\", \"system_wrappers_unittests\": \"74b82e44e629c6d98d593fed0375ee4409b5e5e6\", \"test_support_unittests\": \"d3bba4787f182e1c7e7a0c009987554ae76d3c25\", \"tools_unittests\": \"dea4025c9120eca87db0f15754ee9c9381329729\", \"video_engine_tests\": \"8b5be24a4dbb2ce51900cd96d6a94b9d4fbda7e9\", \"voip_unittests\": \"84f978a02bcc232826c0e16c7338cf738fea8a69\", \"webrtc_nonparallel_tests\": \"56c650d88cc331af8044dd3140384643a72fa457\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_236ad0e06710bb1aa723bf85e2819a73a68bd90c872fb4d36ce08423aaad3728_v2\", \"builder_44ab13d9f6af89a5c9d596ceb4b695121ed6978cb44b3ee6f591cb001a7e97f9_v2\", \"builder_55aa4c084be5ed9d3954314e1098bc0d32b90f37d826af3d055ec49a08faf339_v2\", \"builder_59a5368743e72312f218d070f96b6b81e1ec9c9e3b8bb189152c56cc8ec74c28_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-4-j0t0\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"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\": [\"5218-123b763\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1593462283813376",
"status": "COMPLETED",
"status_changed_ts": "1593463000489046",
"tags": [
"build_address:luci.webrtc.ci/Linux64 Debug/25829",
"builder:Linux64 Debug",
"buildset:commit/git/67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/67123ffa85c99c6ca93c50ca3df7b7fcb1d41fdb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9041558051979219792",
"scheduler_job_id:webrtc/Linux64 Debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8876127983451124128/+/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:4d1a8a0fd4a91410",
"user_agent:luci-scheduler"
],
"updated_ts": "1593463000499630",
"url": "https://ci.chromium.org/b/8876127983451124128",
"utcnow_ts": "1593463001814967"
}
}
[20:36:41.825] Invocation finished in 21m14.086687947s with status SUCCEEDED