[00:42:52.685] New invocation is queued and will start shortly
[00:42:54.215] Starting the invocation (attempt 1)
[00:42:54.272] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:42:54.272] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:42:54.272] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9082762847362384464",
"parameters_json": "{\"builder_name\":\"Linux64 Debug\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src\",\"revision\":\"4f5975c99024fe253a342da06532b0ddf7de687a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux64 Debug",
"scheduler_invocation_id:9082762847362384464",
"scheduler_job_id:webrtc/Linux64 Debug",
"user_agent:luci-scheduler",
"buildset:commit/git/4f5975c99024fe253a342da06532b0ddf7de687a",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/4f5975c99024fe253a342da06532b0ddf7de687a",
"gitiles_ref:refs/heads/master"
]
}
[00:42:56.923] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"created_by": "project:webrtc",
"created_ts": "1554165774682584",
"id": "8917332784604797232",
"parameters_json": "{\"builder_name\": \"Linux64 Debug\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"4f5975c99024fe253a342da06532b0ddf7de687a\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554165776296653",
"tags": [
"build_address:luci.webrtc.ci/Linux64 Debug/21848",
"builder:Linux64 Debug",
"buildset:commit/git/4f5975c99024fe253a342da06532b0ddf7de687a",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/4f5975c99024fe253a342da06532b0ddf7de687a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082762847362384464",
"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/8917332784604797232/+/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": "1554165776296771",
"url": "https://ci.chromium.org/b/8917332784604797232",
"utcnow_ts": "1554165776903486"
}
}
[00:42:56.923] Task URL: https://ci.chromium.org/b/8917332784604797232
[00:42:56.923] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:2:0) after 1m0s
[00:43:56.947] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:2:0)
[00:43:56.947] Timer tick, asking Buildbucket for the build status
[00:43:57.096] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:57.096] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:3:0) after 1m0s
[00:44:57.107] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:3:0)
[00:44:57.107] Timer tick, asking Buildbucket for the build status
[00:44:57.271] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:57.271] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:4:0) after 1m0s
[00:45:57.300] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:4:0)
[00:45:57.300] Timer tick, asking Buildbucket for the build status
[00:45:57.463] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:57.463] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:5:0) after 1m0s
[00:46:57.483] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:5:0)
[00:46:57.483] Timer tick, asking Buildbucket for the build status
[00:46:57.575] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:57.575] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:6:0) after 1m0s
[00:47:57.572] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:6:0)
[00:47:57.572] Timer tick, asking Buildbucket for the build status
[00:47:57.634] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:57.634] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:7:0) after 1m0s
[00:48:57.654] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:7:0)
[00:48:57.654] Timer tick, asking Buildbucket for the build status
[00:48:57.742] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:57.742] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:8:0) after 1m0s
[00:49:57.760] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:8:0)
[00:49:57.760] Timer tick, asking Buildbucket for the build status
[00:49:58.074] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:58.074] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:9:0) after 1m0s
[00:50:58.101] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:9:0)
[00:50:58.101] Timer tick, asking Buildbucket for the build status
[00:50:58.200] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:58.200] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:10:0) after 1m0s
[00:51:58.218] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:10:0)
[00:51:58.218] Timer tick, asking Buildbucket for the build status
[00:51:58.305] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:58.305] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:11:0) after 1m0s
[00:52:58.295] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:11:0)
[00:52:58.295] Timer tick, asking Buildbucket for the build status
[00:52:58.478] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:58.478] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:12:0) after 1m0s
[00:53:58.491] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:12:0)
[00:53:58.491] Timer tick, asking Buildbucket for the build status
[00:53:58.743] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:58.743] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:13:0) after 1m0s
[00:54:58.841] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:13:0)
[00:54:58.841] Timer tick, asking Buildbucket for the build status
[00:54:59.129] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:59.129] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:14:0) after 1m0s
[00:55:59.149] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:14:0)
[00:55:59.149] Timer tick, asking Buildbucket for the build status
[00:55:59.257] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:59.257] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:15:0) after 1m0s
[00:56:59.237] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:15:0)
[00:56:59.237] Timer tick, asking Buildbucket for the build status
[00:56:59.458] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:59.458] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:16:0) after 1m0s
[00:57:59.479] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:16:0)
[00:57:59.479] Timer tick, asking Buildbucket for the build status
[00:57:59.572] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:59.572] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:17:0) after 1m0s
[00:58:59.627] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:17:0)
[00:58:59.627] Timer tick, asking Buildbucket for the build status
[00:58:59.708] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:59.708] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:18:0) after 1m0s
[00:59:59.682] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:18:0)
[00:59:59.682] Timer tick, asking Buildbucket for the build status
[00:59:59.946] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:59.946] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:19:0) after 1m0s
[01:00:59.965] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:19:0)
[01:00:59.965] Timer tick, asking Buildbucket for the build status
[01:01:00.231] Build 8917332784604797232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:00.232] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:20:0) after 1m0s
[01:01:38.398] Received PubSub notification, asking Buildbucket for the build status
[01:01:38.597] Build 8917332784604797232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:00.241] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:20:0)
[01:02:00.241] Timer tick, asking Buildbucket for the build status
[01:02:00.404] Build 8917332784604797232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:00.404] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:22:0) after 1m0s
[01:03:00.432] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:22:0)
[01:03:00.432] Timer tick, asking Buildbucket for the build status
[01:03:00.544] Build 8917332784604797232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:00.544] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:23:0) after 1m0s
[01:04:00.563] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:23:0)
[01:04:00.563] Timer tick, asking Buildbucket for the build status
[01:04:00.754] Build 8917332784604797232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:00.754] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:24:0) after 1m0s
[01:05:00.750] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:24:0)
[01:05:00.750] Timer tick, asking Buildbucket for the build status
[01:05:00.815] Build 8917332784604797232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:00.815] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:25:0) after 1m0s
[01:06:00.827] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:25:0)
[01:06:00.827] Timer tick, asking Buildbucket for the build status
[01:06:00.980] Build 8917332784604797232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:00.980] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:26:0) after 1m0s
[01:07:00.998] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:26:0)
[01:07:00.998] Timer tick, asking Buildbucket for the build status
[01:07:01.042] Build 8917332784604797232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:01.042] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:27:0) after 1m0s
[01:08:01.081] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:27:0)
[01:08:01.081] Timer tick, asking Buildbucket for the build status
[01:08:01.237] Build 8917332784604797232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:01.237] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:28:0) after 1m0s
[01:09:01.258] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:28:0)
[01:09:01.258] Timer tick, asking Buildbucket for the build status
[01:09:01.422] Build 8917332784604797232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:01.422] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:29:0) after 1m0s
[01:10:01.441] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:29:0)
[01:10:01.441] Timer tick, asking Buildbucket for the build status
[01:10:01.527] Build 8917332784604797232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:01.527] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:30:0) after 1m0s
[01:11:01.563] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:30:0)
[01:11:01.563] Timer tick, asking Buildbucket for the build status
[01:11:01.863] Build 8917332784604797232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:01.863] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Debug:9082762847362384464:31:0) after 1m0s
[01:11:23.330] Received PubSub notification, asking Buildbucket for the build status
[01:11:23.459] Build 8917332784604797232: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:11:23.459] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"completed_ts": "1554167482792609",
"created_by": "project:webrtc",
"created_ts": "1554165774682584",
"id": "8917332784604797232",
"parameters_json": "{\"builder_name\": \"Linux64 Debug\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"4f5975c99024fe253a342da06532b0ddf7de687a\"}}",
"project": "webrtc",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$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}, \"bot_id\": \"luci-webrtc-ci-trusty-3-3upt\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux64 Debug\", \"buildnumber\": 21848, \"got_revision\": \"4f5975c99024fe253a342da06532b0ddf7de687a\", \"got_revision_cp\": \"refs/heads/master@{#27406}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.webrtc\", \"path_config\": \"generic\", \"recipe\": \"webrtc/standalone\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"4f5975c99024fe253a342da06532b0ddf7de687a\", \"swarm_hashes\": {\"audio_decoder_unittests\": \"a5b70bc06ad19d65bb28542dd186f07a7f9295bf\", \"common_audio_unittests\": \"c9de40c54dbb64e5ee836f0c43567889a586dd06\", \"common_video_unittests\": \"e049c6680b7beedeb65ac6321002b56dd8919d83\", \"low_bandwidth_audio_test\": \"0cc072a06132d1d15eb33e6350dc24e5e6cf43f2\", \"modules_tests\": \"dd82cdb6f7fb30d680dc7e65eb88b152f54f2406\", \"modules_unittests\": \"db0c3d8f82964210f364c820dea773fd7e8cdc00\", \"peerconnection_unittests\": \"6f54a1cd57e2b1d106918ca2f9e95ebfd503db65\", \"rtc_media_unittests\": \"a588a8d80bf0e6b40f67778ea5322f964fa1777f\", \"rtc_pc_unittests\": \"fe74070ca9578f1c52e626e4a7321109e08aa8df\", \"rtc_stats_unittests\": \"ff6d953b8596cd032ddf2f167d3d9806363844c0\", \"rtc_unittests\": \"3cad458eff2b30e56990a3868211c6983402d64c\", \"slow_tests\": \"4306bcf060b242ec89887913e7a9c31155cae640\", \"system_wrappers_unittests\": \"8284d1d992b4f029c9fa3ee49b8171bae4ca9810\", \"test_support_unittests\": \"f3d8dafa8b8fcba56f8097e7a66bd76a6b27a633\", \"tools_unittests\": \"72bd0d750f5bdd2efd9f8f24371985421ec5b388\", \"video_engine_tests\": \"d0bcb2e7bd6678a1521ff2543181eadf641466e2\", \"webrtc_nonparallel_tests\": \"ba011cb79cbb1d6908f7a17f26cb8a0d23091f1a\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_ee80cf8bba7c81fee9e980afd619ea0acf20624840b3b5e7cf3f2ef0d6b70dc4_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-webrtc-ci-trusty-3-3upt\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.webrtc.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4231-d78bb73\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1554166897821936",
"status": "COMPLETED",
"status_changed_ts": "1554167483083537",
"tags": [
"build_address:luci.webrtc.ci/Linux64 Debug/21848",
"builder:Linux64 Debug",
"buildset:commit/git/4f5975c99024fe253a342da06532b0ddf7de687a",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/4f5975c99024fe253a342da06532b0ddf7de687a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082762847362384464",
"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/8917332784604797232/+/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:43f45149918f7410",
"user_agent:luci-scheduler"
],
"updated_ts": "1554167483086256",
"url": "https://ci.chromium.org/b/8917332784604797232",
"utcnow_ts": "1554167483447691"
}
}
[01:11:23.459] Invocation finished in 28m30.790019077s with status SUCCEEDED