[14:24:55.553] New invocation is queued and will start shortly
[14:24:56.723] Starting the invocation (attempt 1)
[14:24:56.753] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:24:56.753] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:24:56.753] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9084613664880122976",
"parameters_json": "{\"builder_name\":\"Linux64 Release\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src/\",\"revision\":\"cb96809e46752a2ab11d7618d9b6b4191dc84f7f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux64 Release",
"scheduler_invocation_id:9084613664880122976",
"scheduler_job_id:webrtc/Linux64 Release",
"user_agent:luci-scheduler",
"buildset:commit/git/cb96809e46752a2ab11d7618d9b6b4191dc84f7f",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/cb96809e46752a2ab11d7618d9b6b4191dc84f7f",
"gitiles_ref:refs/heads/master"
]
}
[14:24:58.784] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552400696875967",
"id": "8919183602831875728",
"lease_expiration_ts": "1552515897917367",
"lease_key": "124901374",
"parameters_json": "{\"builder_name\": \"Linux64 Release\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src/\", \"revision\": \"cb96809e46752a2ab11d7618d9b6b4191dc84f7f\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552400698465411",
"tags": [
"build_address:luci.webrtc.ci/Linux64 Release/20998",
"builder:Linux64 Release",
"buildset:commit/git/cb96809e46752a2ab11d7618d9b6b4191dc84f7f",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/cb96809e46752a2ab11d7618d9b6b4191dc84f7f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084613664880122976",
"scheduler_job_id:webrtc/Linux64 Release",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8919183602831875728/+/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:438b1c50f2b8ff10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552400698465547",
"url": "https://ci.chromium.org/b/8919183602831875728",
"utcnow_ts": "1552400698762877"
}
}
[14:24:58.784] Task URL: https://ci.chromium.org/b/8919183602831875728
[14:24:58.784] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:2:0) after 1m0s
[14:25:58.772] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:2:0)
[14:25:58.772] Timer tick, asking Buildbucket for the build status
[14:25:58.885] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:25:58.885] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:3:0) after 1m0s
[14:26:58.901] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:3:0)
[14:26:58.901] Timer tick, asking Buildbucket for the build status
[14:26:59.228] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:26:59.228] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:4:0) after 1m0s
[14:27:59.206] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:4:0)
[14:27:59.206] Timer tick, asking Buildbucket for the build status
[14:27:59.376] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:27:59.376] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:5:0) after 1m0s
[14:28:59.398] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:5:0)
[14:28:59.398] Timer tick, asking Buildbucket for the build status
[14:28:59.491] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:28:59.491] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:6:0) after 1m0s
[14:29:59.513] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:6:0)
[14:29:59.513] Timer tick, asking Buildbucket for the build status
[14:29:59.591] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:29:59.591] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:7:0) after 1m0s
[14:30:59.609] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:7:0)
[14:30:59.609] Timer tick, asking Buildbucket for the build status
[14:30:59.689] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:30:59.689] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:8:0) after 1m0s
[14:31:59.735] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:8:0)
[14:31:59.735] Timer tick, asking Buildbucket for the build status
[14:31:59.821] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:31:59.821] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:9:0) after 1m0s
[14:32:59.838] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:9:0)
[14:32:59.838] Timer tick, asking Buildbucket for the build status
[14:32:59.985] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:32:59.985] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:10:0) after 1m0s
[14:34:00.001] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:10:0)
[14:34:00.001] Timer tick, asking Buildbucket for the build status
[14:34:00.094] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:34:00.094] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:11:0) after 1m0s
[14:35:00.088] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:11:0)
[14:35:00.088] Timer tick, asking Buildbucket for the build status
[14:35:00.169] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:35:00.169] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:12:0) after 1m0s
[14:36:00.169] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:12:0)
[14:36:00.169] Timer tick, asking Buildbucket for the build status
[14:36:00.287] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:36:00.287] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:13:0) after 1m0s
[14:37:00.272] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:13:0)
[14:37:00.272] Timer tick, asking Buildbucket for the build status
[14:37:00.435] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:37:00.435] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:14:0) after 1m0s
[14:38:00.420] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:14:0)
[14:38:00.420] Timer tick, asking Buildbucket for the build status
[14:38:00.793] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:38:00.793] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:15:0) after 1m0s
[14:39:00.810] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:15:0)
[14:39:00.810] Timer tick, asking Buildbucket for the build status
[14:39:00.920] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:39:00.920] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:16:0) after 1m0s
[14:40:00.901] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:16:0)
[14:40:00.901] Timer tick, asking Buildbucket for the build status
[14:40:01.005] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:40:01.005] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:17:0) after 1m0s
[14:41:00.985] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:17:0)
[14:41:00.985] Timer tick, asking Buildbucket for the build status
[14:41:01.180] Build 8919183602831875728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:41:01.180] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:18:0) after 1m0s
[14:42:01.148] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:18:0)
[14:42:01.148] Timer tick, asking Buildbucket for the build status
[14:42:01.269] Build 8919183602831875728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:01.269] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:19:0) after 1m0s
[14:42:02.972] Received PubSub notification, asking Buildbucket for the build status
[14:42:03.027] Build 8919183602831875728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:01.289] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:19:0)
[14:43:01.289] Timer tick, asking Buildbucket for the build status
[14:43:01.418] Build 8919183602831875728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:01.418] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:21:0) after 1m0s
[14:44:01.437] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:21:0)
[14:44:01.437] Timer tick, asking Buildbucket for the build status
[14:44:01.485] Build 8919183602831875728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:01.485] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:22:0) after 1m0s
[14:45:01.510] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:22:0)
[14:45:01.510] Timer tick, asking Buildbucket for the build status
[14:45:01.676] Build 8919183602831875728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:01.676] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:23:0) after 1m0s
[14:46:01.687] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:23:0)
[14:46:01.687] Timer tick, asking Buildbucket for the build status
[14:46:01.747] Build 8919183602831875728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:01.747] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:24:0) after 1m0s
[14:47:01.763] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:24:0)
[14:47:01.763] Timer tick, asking Buildbucket for the build status
[14:47:01.833] Build 8919183602831875728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:01.833] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:25:0) after 1m0s
[14:48:01.821] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:25:0)
[14:48:01.821] Timer tick, asking Buildbucket for the build status
[14:48:01.954] Build 8919183602831875728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:01.954] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:26:0) after 1m0s
[14:49:01.956] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:26:0)
[14:49:01.956] Timer tick, asking Buildbucket for the build status
[14:49:02.088] Build 8919183602831875728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:02.088] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:27:0) after 1m0s
[14:50:02.108] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:27:0)
[14:50:02.108] Timer tick, asking Buildbucket for the build status
[14:50:02.264] Build 8919183602831875728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:02.264] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:28:0) after 1m0s
[14:51:02.254] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:28:0)
[14:51:02.254] Timer tick, asking Buildbucket for the build status
[14:51:02.446] Build 8919183602831875728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:02.446] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:29:0) after 1m0s
[14:52:02.474] Handling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:29:0)
[14:52:02.474] Timer tick, asking Buildbucket for the build status
[14:52:02.588] Build 8919183602831875728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:02.588] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux64 Release:9084613664880122976:30:0) after 1m0s
[14:52:12.847] Received PubSub notification, asking Buildbucket for the build status
[14:52:12.903] Build 8919183602831875728: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:52:12.903] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"completed_ts": "1552402331945248",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552400696875967",
"id": "8919183602831875728",
"parameters_json": "{\"builder_name\": \"Linux64 Release\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src/\", \"revision\": \"cb96809e46752a2ab11d7618d9b6b4191dc84f7f\"}}",
"project": "webrtc",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"4158-1053c58\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"trusty-webrtc-d2a17ac3-us-central1-b-g1ct\"], \"pool\": [\"luci.webrtc.ci\"], \"caches\": [\"builder_0b9ea9fa64bf0cd9c46059730b1bdd1d12ff6500ce62c56e48fe9cca98bdf248_v2\", \"builder_0cd671f6f485fc3166b8a13bcda7c7c85f534360e51d98839e042fae0638f2a6_v2\", \"builder_5a4e8638b91acb66c3ac6cbd7815a7f9c99553eb1e8d9fd19cfe7b821cf72739_v2\", \"builder_81b0626a7d0917732ec83f6c2db3e335641e8b10d4b942186c5fc902ad65c830_v2\", \"builder_9e79062e5d3768761821c29cf99044627265ebba18113d0281c5efcb6dd834e3_v2\", \"builder_b6e955e353d7ad729db4d58448beff3ae66bc2502b03a8d5aa67f12ea70e7a83_v2\", \"builder_c07c6aa6d1c1b67d8a4e8f97b8bdf68f3af1543bb4dcfc73adac20d8532d9b4f_v2\", \"builder_e13c935ebc65dc2cf0fcf9090194dc3ad0db6dbd49380f1c9a432e16039a2bfe_v2\", \"builder_ee30cfe615ad79242c90cf868fc3e32771e3342e63241239745f710a5c60d840_v2\", \"builder_ee80cf8bba7c81fee9e980afd619ea0acf20624840b3b5e7cf3f2ef0d6b70dc4_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"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}, \"repository\": \"https://webrtc.googlesource.com/src/\", \"buildername\": \"Linux64 Release\", \"got_revision\": \"cb96809e46752a2ab11d7618d9b6b4191dc84f7f\", \"recipe\": \"webrtc/standalone\", \"mastername\": \"client.webrtc\", \"buildnumber\": 20998, \"isolate_server\": \"https://isolateserver.appspot.com\", \"got_revision_cp\": \"refs/heads/master@{#27076}\", \"swarm_hashes\": {\"modules_unittests\": \"f05c272f08784c1b6c6fbe0424be48702c3a4ff0\", \"common_audio_unittests\": \"b2fa602627f732bf34163ea038b6420c80daecfe\", \"system_wrappers_unittests\": \"0460fb3cd7444d6a80066f8922ab4ad7e31f8dcf\", \"rtc_unittests\": \"cdce14b4b316c26f6cf758820615799ef8f51492\", \"test_support_unittests\": \"769178e6438a201b595aa4878c2500be4d732362\", \"tools_unittests\": \"05ccbdc3e5f6d13248a7d36223fe3a057b287f83\", \"slow_tests\": \"06032c0686c5f37095b15f28704c7b44a0422ce2\", \"rtc_pc_unittests\": \"374a9923c6ee085ac21ec53064b83beb6988eb1b\", \"peerconnection_unittests\": \"fe81478afc80e9f72cbb5789bd09fdbb93a981ec\", \"common_video_unittests\": \"c840b71f93ada219b0368750f1037bf5f0e179c8\", \"video_engine_tests\": \"43a0b708e71d78b1c2123a42f110a2014668131a\", \"webrtc_nonparallel_tests\": \"d0c874e3bdcfe5da6e4378f8e1f942f20e43204b\", \"modules_tests\": \"6d25bf7294b878ba5c5cf1ee078f8e645f8c4642\", \"rtc_stats_unittests\": \"a8a3f8e73853d6a230452796738845f4f5b41289\", \"rtc_media_unittests\": \"94625a0f0895d01db30344de465eaa3cd6caa4fa\", \"audio_decoder_unittests\": \"6213041bc58e482a22de2201a2706e212b1c6854\", \"low_bandwidth_audio_test\": \"8beb13ba7fb15afe94cf958391e896350f4469ce\", \"video_capture_tests\": \"546aa5ca594be29119364b7b14cefd05565953bb\"}, \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"bot_id\": \"trusty-webrtc-d2a17ac3-us-central1-b-g1ct\", \"revision\": \"cb96809e46752a2ab11d7618d9b6b4191dc84f7f\"}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552401719495626",
"status": "COMPLETED",
"status_changed_ts": "1552402332657903",
"tags": [
"build_address:luci.webrtc.ci/Linux64 Release/20998",
"builder:Linux64 Release",
"buildset:commit/git/cb96809e46752a2ab11d7618d9b6b4191dc84f7f",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/cb96809e46752a2ab11d7618d9b6b4191dc84f7f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084613664880122976",
"scheduler_job_id:webrtc/Linux64 Release",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8919183602831875728/+/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:438b1c50f2b8ff10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552402332661354",
"url": "https://ci.chromium.org/b/8919183602831875728",
"utcnow_ts": "1552402332884632"
}
}
[14:52:12.903] Invocation finished in 27m17.365032611s with status SUCCEEDED